Closed
Bug 1689067
Opened 5 years ago
Closed 3 years ago
Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/dom/media/systemservices/CamerasParent.cpp:1053:17 in mozilla::camera::CamerasParent::RecvAllDone()
Categories
(Core :: Audio/Video, defect, P5)
Core
Audio/Video
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure)
Filed by: dluca [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=327948325&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Bk0aaxqGQValrqTH04PuAA/runs/0/artifacts/public/logs/live_backing.log
INFO - TEST-OK | browser/base/content/test/webrtc/browser_webrtc_hooks.js | took 1858ms
[task 2021-01-27T08:57:36.289Z] 08:57:36 INFO - checking window state
[task 2021-01-27T08:57:36.701Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.004267
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | 0.000241 | 0.000241 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | 0.005436 | 0.005195 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | 0.072790 | 0.067354 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | 1.689227 | 1.616437 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.702Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {91ed689e-cea5-4646-9299-e1db818db33a}
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.103281
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.000163 | 0.000163 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.001527 | 0.001364 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.281663 | 0.280136 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 1.593383 | 1.311720 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {aefd1fa0-14fe-4023-a9e7-15d047e0f0a4}
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.405527
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.000160 | 0.000160 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.001686 | 0.001526 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 0.030823 | 0.029137 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | 1.291840 | 1.261017 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.707Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {fded6f1d-f3b8-43f9-a940-af16e4cec341}
[task 2021-01-27T08:57:36.715Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.455887
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | 0.000159 | 0.000159 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | 0.001626 | 0.001467 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | 1.252197 | 1.250571 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {0204b75e-dae4-480a-be57-4bee4393f1ee}
[task 2021-01-27T08:57:36.716Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.508781
[task 2021-01-27T08:57:36.717Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.721Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.722Z] 08:57:36 INFO - GECKO(2265) | 0.000150 | 0.000150 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.723Z] 08:57:36 INFO - GECKO(2265) | 0.001664 | 0.001514 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.723Z] 08:57:36 INFO - GECKO(2265) | 0.024615 | 0.022951 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.724Z] 08:57:36 INFO - GECKO(2265) | 1.200798 | 1.176183 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.724Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {e7803c30-0a13-4d48-a83a-44be3e5ff88b}
[task 2021-01-27T08:57:36.728Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.553495
[task 2021-01-27T08:57:36.730Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.730Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.730Z] 08:57:36 INFO - GECKO(2265) | 0.000147 | 0.000147 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.731Z] 08:57:36 INFO - GECKO(2265) | 0.001507 | 0.001360 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.732Z] 08:57:36 INFO - GECKO(2265) | 1.166159 | 1.164652 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.733Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {dfa0e432-e258-4123-bf7a-51c9199da061}
[task 2021-01-27T08:57:36.733Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.608712
[task 2021-01-27T08:57:36.734Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.735Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.735Z] 08:57:36 INFO - GECKO(2265) | 0.000151 | 0.000151 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.736Z] 08:57:36 INFO - GECKO(2265) | 0.001737 | 0.001586 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.736Z] 08:57:36 INFO - GECKO(2265) | 1.111816 | 1.110079 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.740Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {b89eb43d-c1dc-4dfb-9260-459e322e8b15}
[task 2021-01-27T08:57:36.740Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.654310
[task 2021-01-27T08:57:36.741Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.742Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.742Z] 08:57:36 INFO - GECKO(2265) | 0.000149 | 0.000149 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.743Z] 08:57:36 INFO - GECKO(2265) | 0.001647 | 0.001498 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.746Z] 08:57:36 INFO - GECKO(2265) | 0.030833 | 0.029186 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.747Z] 08:57:36 INFO - GECKO(2265) | 1.066527 | 1.035694 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.747Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {9253e051-ed78-4f4a-b24c-2e26f3bd8b7d}
[task 2021-01-27T08:57:36.748Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.704625
[task 2021-01-27T08:57:36.749Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.750Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.751Z] 08:57:36 INFO - GECKO(2265) | 0.000147 | 0.000147 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.752Z] 08:57:36 INFO - GECKO(2265) | 0.001741 | 0.001594 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.754Z] 08:57:36 INFO - GECKO(2265) | 0.045292 | 0.043551 | Create Offer | PeerConnectionImpl.cpp:1206 | CreateOffer
[task 2021-01-27T08:57:36.755Z] 08:57:36 INFO - GECKO(2265) | 1.016541 | 0.971249 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.755Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {603d9898-4fac-4860-b9e3-f72facda7553}
[task 2021-01-27T08:57:36.756Z] 08:57:36 INFO - GECKO(2265) | Timecard created 1611737855.768411
[task 2021-01-27T08:57:36.756Z] 08:57:36 INFO - GECKO(2265) | Timestamp | Delta | Event | File | Function
[task 2021-01-27T08:57:36.757Z] 08:57:36 INFO - GECKO(2265) | ========================================================================================================
[task 2021-01-27T08:57:36.758Z] 08:57:36 INFO - GECKO(2265) | 0.000147 | 0.000147 | Constructor Completed | PeerConnectionImpl.cpp:326 | PeerConnectionImpl
[task 2021-01-27T08:57:36.758Z] 08:57:36 INFO - GECKO(2265) | 0.002041 | 0.001894 | Initializing PC Ctx | PeerConnectionImpl.cpp:444 | Initialize
[task 2021-01-27T08:57:36.759Z] 08:57:36 INFO - GECKO(2265) | 0.953087 | 0.951046 | Destructor Invoked | PeerConnectionImpl.cpp:335 | ~PeerConnectionImpl
[task 2021-01-27T08:57:36.759Z] 08:57:36 INFO - GECKO(2265) | [Child 2479: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:360: ~PeerConnectionImpl: PeerConnectionImpl destructor invoked for {baa69f22-c9bc-48e9-ac83-97e09db4f996}
[task 2021-01-27T08:57:39.363Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2583
[task 2021-01-27T08:57:39.427Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2329
[task 2021-01-27T08:57:39.548Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2479
[task 2021-01-27T08:57:39.568Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2443
[task 2021-01-27T08:57:39.609Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2354
[task 2021-01-27T08:57:39.765Z] 08:57:39 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2411
[task 2021-01-27T08:57:42.862Z] 08:57:42 INFO - GECKO(2265) | Completed ShutdownLeaks collections in process 2265
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - TEST-START | Shutdown
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - Browser Chrome Test Summary
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - Passed: 3542
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - Failed: 0
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - Todo: 0
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - Mode: e10s
[task 2021-01-27T08:57:42.863Z] 08:57:42 INFO - *** End BrowserChrome Test Results ***
[task 2021-01-27T08:57:43.588Z] 08:57:43 INFO - GECKO(2265) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-27T08:57:43.589Z] 08:57:43 INFO - GECKO(2265) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2021-01-27T08:57:44.051Z] 08:57:44 INFO - GECKO(2265) | ==================
[task 2021-01-27T08:57:44.051Z] 08:57:44 INFO - GECKO(2265) | WARNING: ThreadSanitizer: data race (pid=2265)
[task 2021-01-27T08:57:44.051Z] 08:57:44 INFO - GECKO(2265) | Write of size 1 at 0x7b3c000af100 by thread T20:
[task 2021-01-27T08:57:44.052Z] 08:57:44 INFO - GECKO(2265) | #0 mozilla::camera::CamerasParent::RecvAllDone() /builds/worker/checkouts/gecko/dom/media/systemservices/CamerasParent.cpp:1053:17 (libxul.so+0x3fd5679)
[task 2021-01-27T08:57:44.054Z] 08:57:44 INFO - GECKO(2265) | #1 mozilla::camera::PCamerasParent::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PCamerasParent.cpp:795:28 (libxul.so+0x19ce500)
[task 2021-01-27T08:57:44.055Z] 08:57:44 INFO - GECKO(2265) | #2 mozilla::ipc::PBackgroundParent::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PBackgroundParent.cpp:3717:32 (libxul.so+0x19510ca)
[task 2021-01-27T08:57:44.056Z] 08:57:44 INFO - GECKO(2265) | #3 mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2153:25 (libxul.so+0x149229b)
[task 2021-01-27T08:57:44.057Z] 08:57:44 INFO - GECKO(2265) | #4 mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2077:9 (libxul.so+0x149064c)
[task 2021-01-27T08:57:44.058Z] 08:57:44 INFO - GECKO(2265) | #5 mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1925:3 (libxul.so+0x149122a)
[task 2021-01-27T08:57:44.060Z] 08:57:44 INFO - GECKO(2265) | #6 mozilla::ipc::MessageChannel::MessageTask::Run() /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1956:13 (libxul.so+0x1491831)
[task 2021-01-27T08:57:44.061Z] 08:57:44 INFO - GECKO(2265) | #7 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1165:16 (libxul.so+0xb8e9cb)
[task 2021-01-27T08:57:44.062Z] 08:57:44 INFO - GECKO(2265) | #8 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb94702)
[task 2021-01-27T08:57:44.063Z] 08:57:44 INFO - GECKO(2265) | #9 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:332:5 (libxul.so+0x1496898)
[task 2021-01-27T08:57:44.064Z] 08:57:44 INFO - GECKO(2265) | #10 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.065Z] 08:57:44 INFO - GECKO(2265) | #11 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.066Z] 08:57:44 INFO - GECKO(2265) | #12 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.067Z] 08:57:44 INFO - GECKO(2265) | #13 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:404:10 (libxul.so+0xb8ae88)
[task 2021-01-27T08:57:44.067Z] 08:57:44 INFO - GECKO(2265) | #14 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4524b)
[task 2021-01-27T08:57:44.070Z] 08:57:44 INFO - GECKO(2265) | Previous read of size 1 at 0x7b3c000af100 by main thread:
[task 2021-01-27T08:57:44.071Z] 08:57:44 INFO - GECKO(2265) | [failed to restore the stack]
[task 2021-01-27T08:57:44.071Z] 08:57:44 INFO - GECKO(2265) | Location is heap block of size 240 at 0x7b3c000af050 allocated by thread T20:
[task 2021-01-27T08:57:44.072Z] 08:57:44 INFO - GECKO(2265) | #0 malloc /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:652:5 (firefox+0x54e5c)
[task 2021-01-27T08:57:44.073Z] 08:57:44 INFO - GECKO(2265) | #1 moz_xmalloc /builds/worker/checkouts/gecko/memory/mozalloc/mozalloc.cpp:52:15 (firefox+0xc9b0b)
[task 2021-01-27T08:57:44.074Z] 08:57:44 INFO - GECKO(2265) | #2 operator new /builds/worker/workspace/obj-build/dist/include/mozilla/cxxalloc.h:33:10 (libxul.so+0x3fd6314)
[task 2021-01-27T08:57:44.075Z] 08:57:44 INFO - GECKO(2265) | #3 MakeAndAddRef<mozilla::camera::CamerasParent> /builds/worker/workspace/obj-build/dist/include/mozilla/RefPtr.h:590:15 (libxul.so+0x3fd6314)
[task 2021-01-27T08:57:44.076Z] 08:57:44 INFO - GECKO(2265) | #4 mozilla::camera::CamerasParent::Create() /builds/worker/checkouts/gecko/dom/media/systemservices/CamerasParent.cpp:1139:10 (libxul.so+0x3fd6314)
[task 2021-01-27T08:57:44.076Z] 08:57:44 INFO - GECKO(2265) | #5 mozilla::ipc::BackgroundParentImpl::AllocPCamerasParent() /builds/worker/checkouts/gecko/ipc/glue/BackgroundParentImpl.cpp:744:7 (libxul.so+0x144c99f)
[task 2021-01-27T08:57:44.077Z] 08:57:44 INFO - GECKO(2265) | #6 mozilla::ipc::PBackgroundParent::OnMessageReceived(IPC::Message const&) /builds/worker/workspace/obj-build/ipc/ipdl/PBackgroundParent.cpp:4502:45 (libxul.so+0x1952f7b)
[task 2021-01-27T08:57:44.078Z] 08:57:44 INFO - GECKO(2265) | #7 mozilla::ipc::MessageChannel::DispatchAsyncMessage(mozilla::ipc::ActorLifecycleProxy*, IPC::Message const&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2153:25 (libxul.so+0x149229b)
[task 2021-01-27T08:57:44.079Z] 08:57:44 INFO - GECKO(2265) | #8 mozilla::ipc::MessageChannel::DispatchMessage(IPC::Message&&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:2077:9 (libxul.so+0x149064c)
[task 2021-01-27T08:57:44.079Z] 08:57:44 INFO - GECKO(2265) | #9 mozilla::ipc::MessageChannel::RunMessage(mozilla::ipc::MessageChannel::MessageTask&) /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1925:3 (libxul.so+0x149122a)
[task 2021-01-27T08:57:44.079Z] 08:57:44 INFO - GECKO(2265) | #10 mozilla::ipc::MessageChannel::MessageTask::Run() /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1956:13 (libxul.so+0x1491831)
[task 2021-01-27T08:57:44.080Z] 08:57:44 INFO - GECKO(2265) | #11 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1165:16 (libxul.so+0xb8e9cb)
[task 2021-01-27T08:57:44.080Z] 08:57:44 INFO - GECKO(2265) | #12 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb94702)
[task 2021-01-27T08:57:44.080Z] 08:57:44 INFO - GECKO(2265) | #13 mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:332:5 (libxul.so+0x1496898)
[task 2021-01-27T08:57:44.080Z] 08:57:44 INFO - GECKO(2265) | #14 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:335:10 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.081Z] 08:57:44 INFO - GECKO(2265) | #15 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:328:3 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.081Z] 08:57:44 INFO - GECKO(2265) | #16 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:310:3 (libxul.so+0x1420b5c)
[task 2021-01-27T08:57:44.081Z] 08:57:44 INFO - GECKO(2265) | #17 nsThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:404:10 (libxul.so+0xb8ae88)
[task 2021-01-27T08:57:44.081Z] 08:57:44 INFO - GECKO(2265) | #18 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4524b)
[task 2021-01-27T08:57:44.081Z] 08:57:44 INFO - GECKO(2265) | Thread T20 'IPDL Background' (tid=2300, running) created by main thread at:
[task 2021-01-27T08:57:44.082Z] 08:57:44 INFO - GECKO(2265) | #0 pthread_create /builds/worker/fetches/llvm-project/llvm/projects/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:966:3 (firefox+0x5668b)
[task 2021-01-27T08:57:44.082Z] 08:57:44 INFO - GECKO(2265) | #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3c3f3)
[task 2021-01-27T08:57:44.083Z] 08:57:44 INFO - GECKO(2265) | #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x30d15)
[task 2021-01-27T08:57:44.083Z] 08:57:44 INFO - GECKO(2265) | #3 nsThread::Init(nsTSubstring<char> const&) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:622:18 (libxul.so+0xb8c378)
[task 2021-01-27T08:57:44.085Z] 08:57:44 INFO - GECKO(2265) | #4 nsThreadManager::NewNamedThread(nsTSubstring<char> const&, unsigned int, nsIThread**) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:641:12 (libxul.so+0xb93afa)
[task 2021-01-27T08:57:44.085Z] 08:57:44 INFO - GECKO(2265) | #5 NS_NewNamedThread(nsTSubstring<char> const&, nsIThread**, already_AddRefed<nsIRunnable>, unsigned int) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:169:57 (libxul.so+0xb9b197)
[task 2021-01-27T08:57:44.085Z] 08:57:44 INFO - GECKO(2265) | #6 NS_NewNamedThread<16> /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:74:10 (libxul.so+0x1468579)
[task 2021-01-27T08:57:44.089Z] 08:57:44 INFO - GECKO(2265) | #7 (anonymous namespace)::ParentImpl::CreateBackgroundThread() /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1241:7 (libxul.so+0x1468579)
[task 2021-01-27T08:57:44.089Z] 08:57:44 INFO - GECKO(2265) | #8 RunOnMainThread /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1451:30 (libxul.so+0x146b1ac)
[task 2021-01-27T08:57:44.089Z] 08:57:44 INFO - GECKO(2265) | #9 (anonymous namespace)::ParentImpl::CreateActorHelper::Run() /builds/worker/checkouts/gecko/ipc/glue/BackgroundImpl.cpp:1470:17 (libxul.so+0x146b1ac)
[task 2021-01-27T08:57:44.091Z] 08:57:44 INFO - GECKO(2265) | #10 mozilla::RunnableTask::Run() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:472:16 (libxul.so+0xb7a872)
[task 2021-01-27T08:57:44.092Z] 08:57:44 INFO - GECKO(2265) | #11 mozilla::TaskController::DoExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:753:26 (libxul.so+0xb786f5)
[task 2021-01-27T08:57:44.092Z] 08:57:44 INFO - GECKO(2265) | #12 mozilla::TaskController::ExecuteNextTaskOnlyMainThreadInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex&> const&) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:611:15 (libxul.so+0xb77076)
[task 2021-01-27T08:57:44.092Z] 08:57:44 INFO - GECKO(2265) | #13 mozilla::TaskController::ProcessPendingMTTask(bool) /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:395:36 (libxul.so+0xb77314)
[task 2021-01-27T08:57:44.095Z] 08:57:44 INFO - GECKO(2265) | #14 operator() /builds/worker/checkouts/gecko/xpcom/threads/TaskController.cpp:136:37 (libxul.so+0xb7d8ca)
[task 2021-01-27T08:57:44.095Z] 08:57:44 INFO - GECKO(2265) | #15 mozilla::detail::RunnableFunction<mozilla::TaskController::InitializeInternal()::$_4>::Run() /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.h:534:5 (libxul.so+0xb7d8ca)
[task 2021-01-27T08:57:44.095Z] 08:57:44 INFO - GECKO(2265) | #16 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1171:16 (libxul.so+0xb8e6f6)
[task 2021-01-27T08:57:44.095Z] 08:57:44 INFO - GECKO(2265) | #17 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:548:10 (libxul.so+0xb94702)
[task 2021-01-27T08:57:44.096Z] 08:57:44 INFO - GECKO(2265) | #18 SpinEventLoopUntil<mozilla::ProcessFailureBehavior::ReportToCaller, (lambda at /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:36)> /builds/worker/workspace/obj-build/dist/include/mozilla/SpinEventLoopUntil.h:93:25 (libxul.so+0xb94182)
[task 2021-01-27T08:57:44.096Z] 08:57:44 INFO - GECKO(2265) | #19 nsThreadManager::SpinEventLoopUntilInternal(nsINestedEventLoopCondition*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:727:8 (libxul.so+0xb94182)
[task 2021-01-27T08:57:44.096Z] 08:57:44 INFO - GECKO(2265) | #20 nsThreadManager::SpinEventLoopUntil(nsINestedEventLoopCondition*) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadManager.cpp:706:10 (libxul.so+0xb94007)
[task 2021-01-27T08:57:44.096Z] 08:57:44 INFO - GECKO(2265) | #21 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0xbb65c5)
[task 2021-01-27T08:57:44.097Z] 08:57:44 INFO - GECKO(2265) | #22 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:925:10 (libxul.so+0x1a6fcb0)
[task 2021-01-27T08:57:44.097Z] 08:57:44 INFO - GECKO(2265) | #23 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x68cd3e9)
[task 2021-01-27T08:57:44.097Z] 08:57:44 INFO - GECKO(2265) | #24 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x68cd3e9)
[task 2021-01-27T08:57:44.099Z] 08:57:44 INFO - GECKO(2265) | #25 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.099Z] 08:57:44 INFO - GECKO(2265) | #26 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.099Z] 08:57:44 INFO - GECKO(2265) | #27 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.099Z] 08:57:44 INFO - GECKO(2265) | #28 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x68b532c)
[task 2021-01-27T08:57:44.100Z] 08:57:44 INFO - GECKO(2265) | #29 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68cdc80)
[task 2021-01-27T08:57:44.100Z] 08:57:44 INFO - GECKO(2265) | #30 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68ce816)
[task 2021-01-27T08:57:44.101Z] 08:57:44 INFO - GECKO(2265) | #31 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:664:8 (libxul.so+0x68ce816)
[task 2021-01-27T08:57:44.101Z] 08:57:44 INFO - GECKO(2265) | #32 js::fun_apply(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/vm/JSFunction.cpp:1169:10 (libxul.so+0x6b1a17c)
[task 2021-01-27T08:57:44.102Z] 08:57:44 INFO - GECKO(2265) | #33 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:503:13 (libxul.so+0x68cd3e9)
[task 2021-01-27T08:57:44.102Z] 08:57:44 INFO - GECKO(2265) | #34 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:594:12 (libxul.so+0x68cd3e9)
[task 2021-01-27T08:57:44.102Z] 08:57:44 INFO - GECKO(2265) | #35 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.104Z] 08:57:44 INFO - GECKO(2265) | #36 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:651:10 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.104Z] 08:57:44 INFO - GECKO(2265) | #37 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3309:16 (libxul.so+0x68c29de)
[task 2021-01-27T08:57:44.104Z] 08:57:44 INFO - GECKO(2265) | #38 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:473:13 (libxul.so+0x68b532c)
[task 2021-01-27T08:57:44.104Z] 08:57:44 INFO - GECKO(2265) | #39 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:619:13 (libxul.so+0x68cdc80)
[task 2021-01-27T08:57:44.104Z] 08:57:44 INFO - GECKO(2265) | #40 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:647:10 (libxul.so+0x68ce816)
[task 2021-01-27T08:57:44.105Z] 08:57:44 INFO - GECKO(2265) | #41 js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:664:8 (libxul.so+0x68ce816)
[task 2021-01-27T08:57:44.105Z] 08:57:44 INFO - GECKO(2265) | #42 JS_CallFunctionValue(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/jsapi.cpp:2798:10 (libxul.so+0x6d934fd)
[task 2021-01-27T08:57:44.109Z] 08:57:44 INFO - GECKO(2265) | #43 nsXPCWrappedJS::CallMethod(unsigned short, nsXPTMethodInfo const*, nsXPTCMiniVariant*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedJSClass.cpp:970:17 (libxul.so+0x1a67574)
[task 2021-01-27T08:57:44.110Z] 08:57:44 INFO - GECKO(2265) | #44 PrepareAndDispatch /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcstubs_x86_64_linux.cpp:115:37 (libxul.so+0xbb753a)
[task 2021-01-27T08:57:44.110Z] 08:57:44 INFO - GECKO(2265) | #45 SharedStub <null> (libxul.so+0xbb68b2)
[task 2021-01-27T08:57:44.110Z] 08:57:44 INFO - GECKO(2265) | #46 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:4930:18 (libxul.so+0x6794779)
[task 2021-01-27T08:57:44.110Z] 08:57:44 INFO - GECKO(2265) | #47 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5351:8 (libxul.so+0x6795988)
[task 2021-01-27T08:57:44.110Z] 08:57:44 INFO - GECKO(2265) | #48 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5410:21 (libxul.so+0x6795d14)
[task 2021-01-27T08:57:44.111Z] 08:57:44 INFO - GECKO(2265) | #49 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x67a03b2)
[task 2021-01-27T08:57:44.111Z] 08:57:44 INFO - GECKO(2265) | #50 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:220:22 (firefox+0xc7c70)
[task 2021-01-27T08:57:44.112Z] 08:57:44 INFO - GECKO(2265) | #51 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:344:16 (firefox+0xc7c70)
[task 2021-01-27T08:57:44.112Z] 08:57:44 INFO - GECKO(2265) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/dom/media/systemservices/CamerasParent.cpp:1053:17 in mozilla::camera::CamerasParent::RecvAllDone()
[task 2021-01-27T08:57:44.112Z] 08:57:44 INFO - GECKO(2265) | ==================
[task 2021-01-27T08:57:44.179Z] 08:57:44 INFO - GECKO(2265) | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2021-01-27T08:57:44.180Z] 08:57:44 INFO - GECKO(2265) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=201.161) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-01-27T08:57:44.180Z] 08:57:44 INFO - GECKO(2265) | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2021-01-27T08:57:44.181Z] 08:57:44 INFO - GECKO(2265) | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2021-01-27T08:57:44.181Z] 08:57:44 INFO - GECKO(2265) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=176.154) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-01-27T08:57:44.181Z] 08:57:44 INFO - GECKO(2265) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=204.449) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-01-27T08:57:44.181Z] 08:57:44 INFO - GECKO(2265) | ###!!! [Child][MessageChannel::SendAndWait] Error: Channel error: cannot send/recv
[task 2021-01-27T08:57:44.181Z] 08:57:44 INFO - GECKO(2265) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=215.522) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2021-01-27T08:57:45.390Z] 08:57:45 INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2021-01-27T08:57:45.390Z] 08:57:45 INFO - Buffered messages finished
[task 2021-01-27T08:57:45.390Z] 08:57:45 ERROR - TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code -6
[task 2021-01-27T08:57:45.394Z] 08:57:45 INFO - runtests.py | Application ran for: 0:03:45.067791
[task 2021-01-27T08:57:45.395Z] 08:57:45 INFO - zombiecheck | Reading PID log: /tmp/tmpTSQeLIpidlog
[task 2021-01-27T08:57:45.395Z] 08:57:45 INFO - ==> process 2265 launched child process 2280
[task 2021-01-27T08:57:45.395Z] 08:57:45 INFO - ==> process 2265 launched child process 2329
[task 2021-01-27T08:57:45.396Z] 08:57:45 INFO - ==> process 2265 launched child process 2354
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - ==> process 2265 launched child process 2411
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - ==> process 2265 launched child process 2443
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - ==> process 2265 launched child process 2479
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - ==> process 2265 launched child process 2583
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2443
[task 2021-01-27T08:57:45.397Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2280
[task 2021-01-27T08:57:45.398Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2411
[task 2021-01-27T08:57:45.398Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2479
[task 2021-01-27T08:57:45.399Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2354
[task 2021-01-27T08:57:45.399Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2583
[task 2021-01-27T08:57:45.399Z] 08:57:45 INFO - zombiecheck | Checking for orphan process with PID: 2329
[task 2021-01-27T08:57:45.399Z] 08:57:45 INFO - Stopping web server
[task 2021-01-27T08:57:45.407Z] 08:57:45 INFO - Stopping web socket server
[task 2021-01-27T08:57:45.427Z] 08:57:45 INFO - Stopping ssltunnel
[task 2021-01-27T08:57:45.448Z] 08:57:45 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-01-27T08:57:45.448Z] 08:57:45 INFO - runtests.py | Running tests: end.
[task 2021-01-27T08:57:45.463Z] 08:57:45 INFO - Buffered messages finished
[task 2021-01-27T08:57:45.463Z] 08:57:45 INFO - Running manifest: browser/components/protocolhandler/test/browser/browser.ini
[task 2021-01-27T08:57:45.479Z] 08:57:45 INFO - Setting pipeline to PAUSED ...
[task 2021-01-27T08:57:45.479Z] 08:57:45 INFO - Pipeline is PREROLLING ...
[task 2021-01-27T08:57:45.479Z] 08:57:45 INFO - Pipeline is PREROLLED ...
[task 2021-01-27T08:57:45.479Z] 08:57:45 INFO - Setting pipeline to PLAYING ...
[task 2021-01-27T08:57:45.479Z] 08:57:45 INFO - New clock: GstSystemClock
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Got EOS from element "pipeline0".
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Execution ended after 0:00:00.033453853
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Setting pipeline to PAUSED ...
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Setting pipeline to READY ...
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - (gst-launch-1.0:3016): GStreamer-CRITICAL **: 08:57:45.512: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Setting pipeline to NULL ...
[task 2021-01-27T08:57:45.515Z] 08:57:45 INFO - Freeing pipeline ...
[task 2021-01-27T08:57:45.531Z] 08:57:45 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-01-27T08:57:46.157Z] 08:57:46 INFO - PID 3032 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-01-27T08:57:46.225Z] 08:57:46 INFO - Increasing default timeout to 120 seconds
[task 2021-01-27T08:57:46.225Z] 08:57:46 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-01-27T08:57:46.225Z] 08:57:46 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-01-27T08:57:46.228Z] 08:57:46 INFO - MochitestServer : launching [u'/builds/worker/workspace/build/tests/bin/xpcshell', '-g', '/builds/worker/workspace/build/application/firefox', '-f', '/builds/worker/workspace/build/tests/bin/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpvkp4fe.mozrunner'; const _SERVER_PORT = '8888'; const _SERVER_ADDR = '127.0.0.1'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/worker/workspace/build/tests/mochitest/server.js']
[task 2021-01-27T08:57:46.228Z] 08:57:46 INFO - runtests.py | Server pid: 3035
[task 2021-01-27T08:57:46.232Z] 08:57:46 INFO - runtests.py | Websocket server pid: 3038
[task 2021-01-27T08:57:46.233Z] 08:57:46 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2021-01-27T08:57:46.241Z] 08:57:46 INFO - runtests.py | SSL tunnel pid: 3041
[task 2021-01-27T08:57:46.799Z] 08:57:46 INFO - runtests.py | Running with scheme: http
[task 2021-01-27T08:57:46.799Z] 08:57:46 INFO - runtests.py | Running with e10s: True
[task 2021-01-27T08:57:46.800Z] 08:57:46 INFO - runtests.py | Running with fission: False
[task 2021-01-27T08:57:46.800Z] 08:57:46 INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-01-27T08:57:46.800Z] 08:57:46 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-01-27T08:57:46.800Z] 08:57:46 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2021-01-27T08:57:46.801Z] 08:57:46 INFO - runtests.py | Running tests: start.
[task 2021-01-27T08:57:46.801Z] 08:57:46 INFO -
[task 2021-01-27T08:57:46.808Z] 08:57:46 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmpvkp4fe.mozrunner
[task 2021-01-27T08:57:46.812Z] 08:57:46 INFO - runtests.py | Application pid: 3060
[task 2021-01-27T08:57:46.812Z] 08:57:46 INFO - TEST-INFO | started process GECKO(3060)
[task 2021-01-27T08:57:50.872Z] 08:57:50 INFO - GECKO(3060) | 1611737870869 Marionette TRACE Marionette enabled
[task 2021-01-27T08:57:51.658Z] 08:57:51 INFO - GECKO(3060) | 1611737871656 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-01-27T08:57:58.809Z] 08:57:58 INFO - GECKO(3060) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new Error("", "(unknown module)"))
[task 2021-01-27T08:58:05.855Z] 08:58:05 INFO - GECKO(3060) | 1611737885853 Marionette TRACE Received observer notification marionette-startup-requested
[task 2021-01-27T08:58:05.858Z] 08:58:05 INFO - GECKO(3060) | 1611737885855 Marionette TRACE Waiting until startup recorder finished recording startup scripts...
[task 2021-01-27T08:58:05.974Z] 08:58:05 INFO - GECKO(3060) | 1611737885966 Marionette TRACE All scripts recorded.
[task 2021-01-27T08:58:05.982Z] 08:58:05 INFO - GECKO(3060) | 1611737885978 Marionette INFO Listening on port 2828
[task 2021-01-27T08:58:05.982Z] 08:58:05 INFO - GECKO(3060) | 1611737885980 Marionette DEBUG Marionette is listening
[task 2021-01-27T08:58:06.284Z] 08:58:06 INFO - GECKO(3060) | 1611737886283 Marionette DEBUG Accepted connection 0 from 127.0.0.1:40888
[task 2021-01-27T08:58:06.345Z] 08:58:06 INFO - GECKO(3060) | 1611737886339 Marionette DEBUG Closed connection 0
[task 2021-01-27T08:58:06.347Z] 08:58:06 INFO - GECKO(3060) | 1611737886343 Marionette DEBUG Accepted connection 1 from 127.0.0.1:40890
[task 2021-01-27T08:58:06.421Z] 08:58:06 INFO - GECKO(3060) | 1611737886412 Marionette DEBUG 1 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2021-01-27T08:58:06.517Z] 08:58:06 INFO - GECKO(3060) | 1611737886507 Marionette DEBUG 1 <- [1,1,null,{"sessionId":"ff003122-e152-424f-a80a-7638622ef7eb","capabilities":{"browserName":"firefox","browserVersion":"87.0a ... p/tmpvkp4fe.mozrunner","moz:shutdownTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true}}]
[task 2021-01-27T08:58:06.573Z] 08:58:06 INFO - GECKO(3060) | 1611737886567 Marionette DEBUG 1 -> [0,2,"Addon:Install",{"path":"/tmp/tmpK7mR4H.zip","temporary":false}]
[task 2021-01-27T08:58:06.870Z] 08:58:06 INFO - GECKO(3060) | 1611737886863 Marionette TRACE [38] MarionetteEvents actor created for window id 24
[task 2021-01-27T08:58:07.291Z] 08:58:07 INFO - GECKO(3060) | 1611737887282 Marionette DEBUG 1 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2021-01-27T08:58:07.331Z] 08:58:07 INFO - GECKO(3060) | 1611737887326 Marionette DEBUG 1 -> [0,3,"Addon:Install",{"path":"/tmp/tmpbViD_i.zip","temporary":false}]
[task 2021-01-27T08:58:07.574Z] 08:58:07 INFO - GECKO(3060) | 1611737887571 Marionette DEBUG 1 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2021-01-27T08:58:07.610Z] 08:58:07 INFO - GECKO(3060) | 1611737887602 Marionette DEBUG 1 -> [0,4,"Marionette:GetContext",{}]
[task 2021-01-27T08:58:07.611Z] 08:58:07 INFO - GECKO(3060) | 1611737887605 Marionette DEBUG 1 <- [1,4,null,{"value":"content"}]
[task 2021-01-27T08:58:07.614Z] 08:58:07 INFO - GECKO(3060) | 1611737887613 Marionette DEBUG 1 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2021-01-27T08:58:07.618Z] 08:58:07 INFO - GECKO(3060) | 1611737887616 Marionette DEBUG 1 <- [1,5,null,{"value":null}]
[task 2021-01-27T08:58:07.634Z] 08:58:07 INFO - GECKO(3060) | 1611737887625 Marionette DEBUG 1 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... testUrl":"about:blank","flavor":"browser-chrome"}],"filename":"tests/mochitest/runtests.py","sandbox":"default","line":1927}]
[task 2021-01-27T08:58:07.650Z] 08:58:07 INFO - GECKO(3060) | 1611737887643 Marionette TRACE [6] MarionetteCommands actor created for window id 2
[task 2021-01-27T08:58:07.706Z] 08:58:07 INFO - GECKO(3060) | 1611737887694 Marionette TRACE [17] MarionetteEvents actor created for window id 2147483649
[task 2021-01-27T08:58:07.971Z] 08:58:07 INFO - GECKO(3060) | 1611737887967 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-01-27T08:58:07.971Z] 08:58:07 INFO - GECKO(3060) | 1611737887968 Marionette TRACE Received observer notification toplevel-window-ready
[task 2021-01-27T08:58:07.989Z] 08:58:07 INFO - GECKO(3060) | 1611737887981 Marionette DEBUG 1 <- [1,6,null,{"value":null}]
[task 2021-01-27T08:58:08.026Z] 08:58:08 INFO - GECKO(3060) | 1611737888018 Marionette DEBUG 1 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2021-01-27T08:58:08.026Z] 08:58:08 INFO - GECKO(3060) | 1611737888021 Marionette DEBUG 1 <- [1,7,null,{"value":null}]
[task 2021-01-27T08:58:08.046Z] 08:58:08 INFO - GECKO(3060) | 1611737888039 Marionette DEBUG 1 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2021-01-27T08:58:08.062Z] 08:58:08 INFO - GECKO(3060) | 1611737888050 Marionette DEBUG 1 <- [1,8,null,{"value":null}]
[task 2021-01-27T08:58:08.227Z] 08:58:08 INFO - runtests.py | Waiting for browser...
[task 2021-01-27T08:58:08.324Z] 08:58:08 INFO - GECKO(3060) | 1611737888309 Marionette DEBUG Closed connection 1
[task 2021-01-27T08:58:08.855Z] 08:58:08 INFO - GECKO(3060) | 1611737888852 Marionette TRACE [38] MarionetteEvents actor created for window id 6442450945
[task 2021-01-27T08:58:09.080Z] 08:58:09 INFO - *** Start BrowserChrome Test Results ***
[task 2021-01-27T08:58:09.297Z] 08:58:09 INFO - checking window state
[task 2021-01-27T08:58:09.357Z] 08:58:09 INFO - GECKO(3060) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-01-27T08:58:09.438Z] 08:58:09 INFO - TEST-START | browser/components/protocolhandler/test/browser/browser_registerProtocolHandler_notification.js
[task 2021-01-27T08:58:10.798Z] 08:58:10 INFO - GECKO(3060) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-01-27T08:58:10.802Z] 08:58:10 INFO - GECKO(3060) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2021-01-27T08:58:10.803Z] 08:58:10 INFO - GECKO(3060) | MEMORY STAT | vsize 130550970MB | residentFast 2051MB
[task 2021-01-27T08:58:10.803Z] 08:58:10 INFO - TEST-OK | browser/components/protocolhandler/test/browser/browser_registerProtocolHandler_notification.js | took 1366ms
[task 2021-01-27T08:58:11.018Z] 08:58:11 INFO - checking window state
[task 2021-01-27T08:58:13.799Z] 08:58:13 INFO - GECKO(3060) | Completed ShutdownLeaks collections in process 3237
[task 2021-01-27T08:58:13.826Z] 08:58:13 INFO - GECKO(3060) | Completed ShutdownLeaks collections in process 3123
[task 2021-01-27T08:58:13.995Z] 08:58:13 INFO - GECKO(3060) | Completed ShutdownLeaks collections in process 3148
[task 2021-01-27T08:58:14.143Z] 08:58:14 INFO - GECKO(3060) | Completed ShutdownLeaks collections in process 3206
[task 2021-01-27T08:58:16.059Z] 08:58:16 INFO - GECKO(3060) | Completed ShutdownLeaks collections in process 3060
[task 2021-01-27T08:58:16.061Z] 08:58:16 INFO - TEST-START | Shutdown
[task 2021-01-27T08:58:16.061Z] 08:58:16 INFO - Browser Chrome Test Summary
[task 2021-01-27T08:58:16.061Z] 08:58:16 INFO - Passed: 5
[task 2021-01-27T08:58:16.061Z] 08:58:16 INFO - Failed: 0
[task 2021-01-27T08:58:16.061Z] 08:58:16 INFO - Todo: 1
[task 2021-01-27T08:58:16.062Z] 08:58:16 INFO - Mode: e10s
[task 2021-01-27T08:58:16.062Z] 08:58:16 INFO - *** End BrowserChrome Test Results ***```
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
|
||
Comment 3•4 years ago
|
||
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=331739949&repo=autoland&lineNumber=3856
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
|
||
Comment 6•4 years ago
|
||
Recent failure: https://treeherder.mozilla.org/logviewer?job_id=338274011&repo=autoland&lineNumber=3742
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 9•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
|
|
||
Comment 10•4 years ago
|
||
Recent failure log : https://treeherder.mozilla.org/logviewer?job_id=357734102&repo=autoland&lineNumber=63417
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 12•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 4 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 13•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=369896687&repo=autoland
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 15•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: REOPENED → RESOLVED
Closed: 4 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•