Closed
Bug 1785018
Opened 2 years ago
Closed 2 years ago
Intermittent SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1483:7 in GetDiskInfo<(lambda at /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1557:7), (lambda at /builds/worker/checkouts
Categories
(Core :: Networking: Cache, defect, P1)
Core
Networking: Cache
Tracking
()
RESOLVED
FIXED
106 Branch
| Tracking | Status | |
|---|---|---|
| firefox-esr91 | --- | unaffected |
| firefox-esr102 | --- | unaffected |
| firefox104 | --- | unaffected |
| firefox105 | --- | disabled |
| firefox106 | --- | fixed |
People
(Reporter: intermittent-bug-filer, Assigned: valentin)
References
(Regression)
Details
(Keywords: csectype-race, intermittent-failure, sec-moderate, Whiteboard: [necko-triaged][Windows and Nightly only][post-critsmash-triage])
Attachments
(1 file)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=387456207&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/J6EcIli7QLqlEez66iGJqA/runs/0/artifacts/public/logs/live_backing.log
[task 2022-08-16T04:30:02.765Z] 04:30:02 INFO - TEST-START | netwerk/test/browser/browser_backgroundtask_purgeHTTPCache.js
[task 2022-08-16T04:30:02.780Z] 04:30:02 INFO - GECKO(5371) | 1660624202778 Marionette TRACE Received observer notification browser-delayed-startup-finished
[task 2022-08-16T04:30:02.781Z] 04:30:02 INFO - GECKO(5371) | JavaScript error: resource:///modules/DevToolsStartup.jsm, line 689: TypeError: can't access property "document", window is null
[task 2022-08-16T04:30:02.858Z] 04:30:02 INFO - GECKO(5371) | *** You are running in background task mode. ***
[task 2022-08-16T04:30:02.858Z] 04:30:02 INFO - GECKO(5371) | *** You are running in headless mode.
[task 2022-08-16T04:30:02.862Z] 04:30:02 INFO - GECKO(5371) | ==================
[task 2022-08-16T04:30:02.863Z] 04:30:02 INFO - GECKO(5371) | WARNING: ThreadSanitizer: data race (pid=5371)
[task 2022-08-16T04:30:02.863Z] 04:30:02 INFO - GECKO(5371) | Read of size 8 at 0x7b100005c060 by thread T13:
[task 2022-08-16T04:30:02.864Z] 04:30:02 INFO - GECKO(5371) | #0 statfs64 /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/../../sanitizer_common/sanitizer_common_interceptors.inc:4485:3 (firefox+0x7c7ad) (BuildId: 84cb6cc786d0b104939000b8a60914095c12d02a)
[task 2022-08-16T04:30:02.872Z] 04:30:02 INFO - GECKO(5371) | #1 GetDiskInfo<(lambda at /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1557:7), (lambda at /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1561:7)> /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1483:7 (libxul.so+0x1145e28) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.873Z] 04:30:02 INFO - GECKO(5371) | #2 nsLocalFile::GetDiskSpaceAvailable(long*) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1556:10 (libxul.so+0x1145e28)
[task 2022-08-16T04:30:02.873Z] 04:30:02 INFO - GECKO(5371) | #3 mozilla::net::CacheFileIOManager::WriteInternal(mozilla::net::CacheFileHandle*, long, char const*, int, bool, bool) /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:2007:27 (libxul.so+0x1893fee) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.873Z] 04:30:02 INFO - GECKO(5371) | #4 mozilla::net::WriteEvent::Run() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:757:43 (libxul.so+0x18c44e0) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.873Z] 04:30:02 INFO - GECKO(5371) | #5 mozilla::net::CacheIOThread::LoopOneLevel(unsigned int) /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:528:22 (libxul.so+0x18ab47d) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.880Z] 04:30:02 INFO - GECKO(5371) | #6 mozilla::net::CacheIOThread::ThreadFunc() /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:465:9 (libxul.so+0x18aabf5) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.880Z] 04:30:02 INFO - GECKO(5371) | #7 mozilla::net::CacheIOThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:406:11 (libxul.so+0x18aa2ee) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.880Z] 04:30:02 INFO - GECKO(5371) | #8 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4615d) (BuildId: d84a2ea637d589bb444b490909c0d630866b95b6)
[task 2022-08-16T04:30:02.880Z] 04:30:02 INFO - GECKO(5371) | Previous write of size 1 at 0x7b100005c062 by main thread:
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #0 nsLocalFile::GetParent(nsIFile**) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1624:11 (libxul.so+0x11435f0) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #1 mozilla::net::CacheFileIOManager::DispatchPurgeTask(nsTString<char> const&, nsTString<char> const&, nsTString<char> const&) /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:4055:25 (libxul.so+0x188edea) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #2 mozilla::net::CacheFileIOManager::OnDelayedStartupFinished() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:1355:16 (libxul.so+0x188ed4a) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #3 mozilla::net::CacheObserver::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/netwerk/cache2/CacheObserver.cpp:246:5 (libxul.so+0x18c0449) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #4 NotifyObservers /builds/worker/checkouts/gecko/xpcom/ds/nsObserverList.cpp:70:19 (libxul.so+0x112c981) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #5 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:291:19 (libxul.so+0x112c981)
[task 2022-08-16T04:30:02.881Z] 04:30:02 INFO - GECKO(5371) | #6 NS_InvokeByIndex /builds/worker/checkouts/gecko/xpcom/reflect/xptcall/md/unix/xptcinvoke_asm_x86_64_unix.S:101 (libxul.so+0x12066f5) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.882Z] 04:30:02 INFO - GECKO(5371) | #7 XPC_WN_CallMethod(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCWrappedNativeJSOps.cpp:965:10 (libxul.so+0x1fd2c76) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.882Z] 04:30:02 INFO - GECKO(5371) | #8 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:455:13 (libxul.so+0x964ff8a) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.882Z] 04:30:02 INFO - GECKO(5371) | #9 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:543:12 (libxul.so+0x964ff8a)
[task 2022-08-16T04:30:02.882Z] 04:30:02 INFO - GECKO(5371) | #10 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:610:10 (libxul.so+0x9645edc) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.882Z] 04:30:02 INFO - GECKO(5371) | #11 CallFromStack /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:615:10 (libxul.so+0x9645edc)
[task 2022-08-16T04:30:02.890Z] 04:30:02 INFO - GECKO(5371) | #12 Interpret(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:3371:16 (libxul.so+0x9645edc)
[task 2022-08-16T04:30:02.891Z] 04:30:02 INFO - GECKO(5371) | #13 js::RunScript(JSContext*, js::RunState&) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:427:13 (libxul.so+0x96391b7) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.894Z] 04:30:02 INFO - GECKO(5371) | #14 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:575:13 (libxul.so+0x965005d) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.894Z] 04:30:02 INFO - GECKO(5371) | #15 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:610:10 (libxul.so+0x9650d8c) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.895Z] 04:30:02 INFO - GECKO(5371) | #16 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:642:8 (libxul.so+0x9650d8c)
[task 2022-08-16T04:30:02.896Z] 04:30:02 INFO - GECKO(5371) | #17 Call /builds/worker/checkouts/gecko/js/src/vm/Interpreter.h:116:10 (libxul.so+0x892ca64) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.897Z] 04:30:02 INFO - GECKO(5371) | #18 PromiseReactionJob(JSContext*, unsigned int, JS::Value*) /builds/worker/checkouts/gecko/js/src/builtin/Promise.cpp:2239:10 (libxul.so+0x892ca64)
[task 2022-08-16T04:30:02.899Z] 04:30:02 INFO - GECKO(5371) | #19 CallJSNative /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:455:13 (libxul.so+0x964ff8a) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.899Z] 04:30:02 INFO - GECKO(5371) | #20 js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason) /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:543:12 (libxul.so+0x964ff8a)
[task 2022-08-16T04:30:02.900Z] 04:30:02 INFO - GECKO(5371) | #21 InternalCall /builds/worker/checkouts/gecko/js/src/vm/Interpreter.cpp:610:10 (libxul.so+0x9650d8c) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.900Z] 04:30:02 INFO - GECKO(5371) | #22 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:642:8 (libxul.so+0x9650d8c)
[task 2022-08-16T04:30:02.901Z] 04:30:02 INFO - GECKO(5371) | #23 JS::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::HandleValueArray const&, JS::MutableHandle<JS::Value>) /builds/worker/checkouts/gecko/js/src/vm/CallAndConstruct.cpp:117:10 (libxul.so+0x87b4341) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.902Z] 04:30:02 INFO - GECKO(5371) | #24 mozilla::dom::PromiseJobCallback::Call(mozilla::dom::BindingCallContext&, JS::Handle<JS::Value>, mozilla::ErrorResult&) /builds/worker/workspace/obj-build/dom/bindings/PromiseBinding.cpp:35:8 (libxul.so+0x326661e) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.903Z] 04:30:02 INFO - GECKO(5371) | #25 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:88:12 (libxul.so+0x10c0e0e) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.903Z] 04:30:02 INFO - GECKO(5371) | #26 Call /builds/worker/workspace/obj-build/dist/include/mozilla/dom/PromiseBinding.h:101:12 (libxul.so+0x10c0e0e)
[task 2022-08-16T04:30:02.904Z] 04:30:02 INFO - GECKO(5371) | #27 mozilla::PromiseJobRunnable::Run(mozilla::AutoSlowOperation&) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:213:18 (libxul.so+0x10c0e0e)
[task 2022-08-16T04:30:02.905Z] 04:30:02 INFO - GECKO(5371) | #28 mozilla::CycleCollectedJSContext::PerformMicroTaskCheckPoint(bool) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:676:17 (libxul.so+0x10add93) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.906Z] 04:30:02 INFO - GECKO(5371) | #29 mozilla::CycleCollectedJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/xpcom/base/CycleCollectedJSContext.cpp:463:3 (libxul.so+0x10aeae7) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.907Z] 04:30:02 INFO - GECKO(5371) | #30 XPCJSContext::AfterProcessTask(unsigned int) /builds/worker/checkouts/gecko/js/xpconnect/src/XPCJSContext.cpp:1480:28 (libxul.so+0x1f9753f) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.907Z] 04:30:02 INFO - GECKO(5371) | #31 nsThread::ProcessNextEvent(bool, bool*) /builds/worker/checkouts/gecko/xpcom/threads/nsThread.cpp:1242:24 (libxul.so+0x11d2da6) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.908Z] 04:30:02 INFO - GECKO(5371) | #32 NS_ProcessNextEvent(nsIThread*, bool) /builds/worker/checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:465:10 (libxul.so+0x11d8f75) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.909Z] 04:30:02 INFO - GECKO(5371) | #33 mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) /builds/worker/checkouts/gecko/ipc/glue/MessagePump.cpp:85:21 (libxul.so+0x1e3db4b) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.910Z] 04:30:02 INFO - GECKO(5371) | #34 RunInternal /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:381:10 (libxul.so+0x1d5c13c) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.910Z] 04:30:02 INFO - GECKO(5371) | #35 RunHandler /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:374:3 (libxul.so+0x1d5c13c)
[task 2022-08-16T04:30:02.911Z] 04:30:02 INFO - GECKO(5371) | #36 MessageLoop::Run() /builds/worker/checkouts/gecko/ipc/chromium/src/base/message_loop.cc:356:3 (libxul.so+0x1d5c13c)
[task 2022-08-16T04:30:02.912Z] 04:30:02 INFO - GECKO(5371) | #37 nsBaseAppShell::Run() /builds/worker/checkouts/gecko/widget/nsBaseAppShell.cpp:150:27 (libxul.so+0x5b54b06) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.913Z] 04:30:02 INFO - GECKO(5371) | #38 nsAppStartup::Run() /builds/worker/checkouts/gecko/toolkit/components/startup/nsAppStartup.cpp:295:30 (libxul.so+0x83dcd33) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.914Z] 04:30:02 INFO - GECKO(5371) | #39 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5718:22 (libxul.so+0x850dcf7) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.914Z] 04:30:02 INFO - GECKO(5371) | #40 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5912:8 (libxul.so+0x850e7b2) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.915Z] 04:30:02 INFO - GECKO(5371) | #41 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5968:21 (libxul.so+0x850ee24) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.916Z] 04:30:02 INFO - GECKO(5371) | #42 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x851b092) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.917Z] 04:30:02 INFO - GECKO(5371) | #43 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:229:22 (firefox+0xdf12f) (BuildId: 84cb6cc786d0b104939000b8a60914095c12d02a)
[task 2022-08-16T04:30:02.918Z] 04:30:02 INFO - GECKO(5371) | #44 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:433:16 (firefox+0xdf12f)
[task 2022-08-16T04:30:02.918Z] 04:30:02 INFO - GECKO(5371) | Location is heap block of size 64 at 0x7b100005c040 allocated by thread T13:
[task 2022-08-16T04:30:02.919Z] 04:30:02 INFO - GECKO(5371) | #0 malloc /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:667:5 (firefox+0x5c821) (BuildId: 84cb6cc786d0b104939000b8a60914095c12d02a)
[task 2022-08-16T04:30:02.920Z] 04:30:02 INFO - GECKO(5371) | #1 Alloc /builds/worker/checkouts/gecko/xpcom/string/nsStringBuffer.cpp:68:42 (libxul.so+0x106e65d) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.921Z] 04:30:02 INFO - GECKO(5371) | #2 nsTSubstring<char>::StartBulkWriteImpl(unsigned long, unsigned long, bool, unsigned long, unsigned long, unsigned long) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:232:32 (libxul.so+0x106e65d)
[task 2022-08-16T04:30:02.922Z] 04:30:02 INFO - GECKO(5371) | #3 SetLength /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:960:12 (libxul.so+0x1079c0e) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.922Z] 04:30:02 INFO - GECKO(5371) | #4 nsTSubstring<char>::EnsureMutable(unsigned long) /builds/worker/checkouts/gecko/xpcom/string/nsTSubstring.cpp:381:10 (libxul.so+0x1079c0e)
[task 2022-08-16T04:30:02.923Z] 04:30:02 INFO - GECKO(5371) | #5 BeginWriting /builds/worker/workspace/obj-build/dist/include/nsTSubstring.h:352:10 (libxul.so+0x1143528) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.924Z] 04:30:02 INFO - GECKO(5371) | #6 nsLocalFile::GetParent(nsIFile**) /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1602:24 (libxul.so+0x1143528)
[task 2022-08-16T04:30:02.925Z] 04:30:02 INFO - GECKO(5371) | #7 mozilla::net::CacheFileIOManager::CreateCacheTree() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:3869:25 (libxul.so+0x18904fa) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.926Z] 04:30:02 INFO - GECKO(5371) | #8 mozilla::net::CacheFileIOManager::OpenSpecialFileInternal(nsTSubstring<char> const&, unsigned int, mozilla::net::CacheFileHandle**) /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:1688:10 (libxul.so+0x18925a2) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.926Z] 04:30:02 INFO - GECKO(5371) | #9 mozilla::net::OpenFileEvent::Run() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:643:22 (libxul.so+0x18c3d65) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.927Z] 04:30:02 INFO - GECKO(5371) | #10 mozilla::net::CacheIOThread::LoopOneLevel(unsigned int) /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:528:22 (libxul.so+0x18ab47d) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.928Z] 04:30:02 INFO - GECKO(5371) | #11 mozilla::net::CacheIOThread::ThreadFunc() /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:465:9 (libxul.so+0x18aabf5) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.929Z] 04:30:02 INFO - GECKO(5371) | #12 mozilla::net::CacheIOThread::ThreadFunc(void*) /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:406:11 (libxul.so+0x18aa2ee) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.929Z] 04:30:02 INFO - GECKO(5371) | #13 _pt_root /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:201:5 (libnspr4.so+0x4615d) (BuildId: d84a2ea637d589bb444b490909c0d630866b95b6)
[task 2022-08-16T04:30:02.930Z] 04:30:02 INFO - GECKO(5371) | Thread T13 'Cache2 I/O' (tid=5399, running) created by main thread at:
[task 2022-08-16T04:30:02.931Z] 04:30:02 INFO - GECKO(5371) | #0 pthread_create /builds/worker/fetches/llvm-project/compiler-rt/lib/tsan/rtl/tsan_interceptors_posix.cpp:1022:3 (firefox+0x5dfdd) (BuildId: 84cb6cc786d0b104939000b8a60914095c12d02a)
[task 2022-08-16T04:30:02.932Z] 04:30:02 INFO - GECKO(5371) | #1 _PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:458:14 (libnspr4.so+0x3d1b5) (BuildId: d84a2ea637d589bb444b490909c0d630866b95b6)
[task 2022-08-16T04:30:02.933Z] 04:30:02 INFO - GECKO(5371) | #2 PR_CreateThread /builds/worker/checkouts/gecko/nsprpub/pr/src/pthreads/ptthread.c:533:12 (libnspr4.so+0x322a5) (BuildId: d84a2ea637d589bb444b490909c0d630866b95b6)
[task 2022-08-16T04:30:02.933Z] 04:30:02 INFO - GECKO(5371) | #3 mozilla::net::CacheIOThread::Init() /builds/worker/checkouts/gecko/netwerk/cache2/CacheIOThread.cpp:239:7 (libxul.so+0x188bbfc) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.934Z] 04:30:02 INFO - GECKO(5371) | #4 mozilla::net::CacheFileIOManager::InitInternal() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:1146:19 (libxul.so+0x188bb10) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.935Z] 04:30:02 INFO - GECKO(5371) | #5 mozilla::net::CacheFileIOManager::Init() /builds/worker/checkouts/gecko/netwerk/cache2/CacheFileIOManager.cpp:1134:24 (libxul.so+0x188b6b2) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.936Z] 04:30:02 INFO - GECKO(5371) | #6 mozilla::net::CacheObserver::Observe(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/netwerk/cache2/CacheObserver.cpp:206:5 (libxul.so+0x18c0561) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.937Z] 04:30:02 INFO - GECKO(5371) | #7 NotifyObservers /builds/worker/checkouts/gecko/xpcom/ds/nsObserverList.cpp:70:19 (libxul.so+0x112c981) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.938Z] 04:30:02 INFO - GECKO(5371) | #8 nsObserverService::NotifyObservers(nsISupports*, char const*, char16_t const*) /builds/worker/checkouts/gecko/xpcom/ds/nsObserverService.cpp:291:19 (libxul.so+0x112c981)
[task 2022-08-16T04:30:02.938Z] 04:30:02 INFO - GECKO(5371) | #9 nsXREDirProvider::DoStartup() /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:889:13 (libxul.so+0x851fbca) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.939Z] 04:30:02 INFO - GECKO(5371) | #10 XREMain::XRE_mainRun() /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5461:18 (libxul.so+0x850d65c) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.943Z] 04:30:02 INFO - GECKO(5371) | #11 XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5912:8 (libxul.so+0x850e7b2) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.944Z] 04:30:02 INFO - GECKO(5371) | #12 XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/nsAppRunner.cpp:5968:21 (libxul.so+0x850ee24) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.945Z] 04:30:02 INFO - GECKO(5371) | #13 mozilla::BootstrapImpl::XRE_main(int, char**, mozilla::BootstrapConfig const&) /builds/worker/checkouts/gecko/toolkit/xre/Bootstrap.cpp:45:12 (libxul.so+0x851b092) (BuildId: c501a5c1640e7e3a210314edcd32c940df760316)
[task 2022-08-16T04:30:02.946Z] 04:30:02 INFO - GECKO(5371) | #14 do_main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:229:22 (firefox+0xdf12f) (BuildId: 84cb6cc786d0b104939000b8a60914095c12d02a)
[task 2022-08-16T04:30:02.947Z] 04:30:02 INFO - GECKO(5371) | #15 main /builds/worker/checkouts/gecko/browser/app/nsBrowserApp.cpp:433:16 (firefox+0xdf12f)
[task 2022-08-16T04:30:02.947Z] 04:30:02 INFO - GECKO(5371) | SUMMARY: ThreadSanitizer: data race /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1483:7 in GetDiskInfo<(lambda at /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1557:7), (lambda at /builds/worker/checkouts/gecko/xpcom/io/nsLocalFileUnix.cpp:1561:7)>
[task 2022-08-16T04:30:02.948Z] 04:30:02 INFO - GECKO(5371) | ==================
[task 2022-08-16T04:30:02.951Z] 04:30:02 INFO - GECKO(5371) | Exiting due to channel error.
[task 2022-08-16T04:30:02.953Z] 04:30:02 INFO - GECKO(5371) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=0.577157) Exiting due to channel error.
[task 2022-08-16T04:30:02.954Z] 04:30:02 INFO - GECKO(5371) | Exiting due to channel error.
[task 2022-08-16T04:30:02.955Z] 04:30:02 INFO - GECKO(5371) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=18.3306) [GFX1-]: Receive IPC close with reason=AbnormalShutdown
[task 2022-08-16T04:30:02.956Z] 04:30:02 INFO - GECKO(5371) | Exiting due to channel error.
[task 2022-08-16T04:30:02.960Z] 04:30:02 INFO - GECKO(5371) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=17.4905) Exiting due to channel error.
[task 2022-08-16T04:30:02.961Z] 04:30:02 INFO - GECKO(5371) | Exiting due to channel error.
[task 2022-08-16T04:30:02.963Z] 04:30:02 INFO - GECKO(5371) | Exiting due to channel error.
[task 2022-08-16T04:30:03.278Z] 04:30:03 INFO - GECKO(5371) | Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=10.5706) Crash Annotation GraphicsCriticalError: |[C0][GFX1-]: Receive IPC close with reason=AbnormalShutdown (t=6.29781) console.error: "/tmp/tmp77_irrvz.mozrunner" "" 0 ".purge.bg_rm"
[task 2022-08-16T04:30:03.289Z] 04:30:03 INFO - GECKO(5371) | console.error: "Deletion of folder cache2.2021-11-25-08-47-04.purge.bg_rm - success=true"
[task 2022-08-16T04:30:04.502Z] 04:30:04 INFO - TEST-INFO | Main app process: killed by SIGIOT
[task 2022-08-16T04:30:04.502Z] 04:30:04 INFO - Buffered messages logged at 04:30:02
[task 2022-08-16T04:30:04.503Z] 04:30:04 INFO - Entering test bound test_startupCleanup
[task 2022-08-16T04:30:04.504Z] 04:30:04 INFO - TEST-PASS | netwerk/test/browser/browser_backgroundtask_purgeHTTPCache.js | Folder /tmp/tmp77_irrvz.mozrunner/cache2.2021-11-25-08-47-04.purge.bg_rm should not exist - false == false -
[task 2022-08-16T04:30:04.505Z] 04:30:04 INFO - TEST-PASS | netwerk/test/browser/browser_backgroundtask_purgeHTTPCache.js | Folder /tmp/tmp77_irrvz.mozrunner/cache2.2021-11-25-08-47-04.purge.bg_rm should have been created - true == true -
[task 2022-08-16T04:30:04.508Z] 04:30:04 INFO - Buffered messages finished
[task 2022-08-16T04:30:04.509Z] 04:30:04 ERROR - TEST-UNEXPECTED-FAIL | netwerk/test/browser/browser_backgroundtask_purgeHTTPCache.js | application terminated with exit code -6
[task 2022-08-16T04:30:04.509Z] 04:30:04 INFO - runtests.py | Application ran for: 0:00:26.022246
[task 2022-08-16T04:30:04.509Z] 04:30:04 INFO - zombiecheck | Reading PID log: /tmp/tmpdcnkotzspidlog
[task 2022-08-16T04:30:04.509Z] 04:30:04 INFO - ==> process 5371 launched child process 5387
[task 2022-08-16T04:30:04.510Z] 04:30:04 INFO - ==> process 5371 launched child process 5445
[task 2022-08-16T04:30:04.510Z] 04:30:04 INFO - ==> process 5371 launched child process 5469
[task 2022-08-16T04:30:04.510Z] 04:30:04 INFO - ==> process 5371 launched child process 5519
[task 2022-08-16T04:30:04.510Z] 04:30:04 INFO - ==> process 5371 launched child process 5522
[task 2022-08-16T04:30:04.511Z] 04:30:04 INFO - ==> process 5371 launched child process 5556
[task 2022-08-16T04:30:04.512Z] 04:30:04 INFO - ==> process 5371 launched child process 5592
[task 2022-08-16T04:30:04.512Z] 04:30:04 INFO - ==> process 5371 launched child process 5614
[task 2022-08-16T04:30:04.512Z] 04:30:04 INFO - ==> process 5371 launched child process 5643
[task 2022-08-16T04:30:04.513Z] 04:30:04 INFO - ==> process 5659 launched child process 5676
[task 2022-08-16T04:30:04.513Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5445
[task 2022-08-16T04:30:04.514Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5387
[task 2022-08-16T04:30:04.514Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5643
[task 2022-08-16T04:30:04.514Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5676
[task 2022-08-16T04:30:04.515Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5614
[task 2022-08-16T04:30:04.516Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5519
[task 2022-08-16T04:30:04.516Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5522
[task 2022-08-16T04:30:04.517Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5556
[task 2022-08-16T04:30:04.517Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5592
[task 2022-08-16T04:30:04.518Z] 04:30:04 INFO - zombiecheck | Checking for orphan process with PID: 5469
[task 2022-08-16T04:30:04.518Z] 04:30:04 INFO - Stopping web server
[task 2022-08-16T04:30:04.523Z] 04:30:04 INFO - Server shut down.
[task 2022-08-16T04:30:04.544Z] 04:30:04 INFO - Web server killed.
[task 2022-08-16T04:30:04.544Z] 04:30:04 INFO - Stopping web socket server
[task 2022-08-16T04:30:04.565Z] 04:30:04 INFO - Stopping ssltunnel
[task 2022-08-16T04:30:04.588Z] 04:30:04 WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2022-08-16T04:30:04.589Z] 04:30:04 INFO - runtests.py | Running tests: end.
[task 2022-08-16T04:30:04.617Z] 04:30:04 INFO - Buffered messages finished
[task 2022-08-16T04:30:04.618Z] 04:30:04 INFO - Running manifest: toolkit/components/nimbus/test/browser/browser.ini
[task 2022-08-16T04:30:04.618Z] 04:30:04 INFO - The following extra prefs will be set:
[task 2022-08-16T04:30:04.618Z] 04:30:04 INFO - app.normandy.run_interval_seconds=0
[task 2022-08-16T04:30:04.639Z] 04:30:04 INFO - Setting pipeline to PAUSED ...
[task 2022-08-16T04:30:04.639Z] 04:30:04 INFO - Pipeline is PREROLLING ...
[task 2022-08-16T04:30:04.641Z] 04:30:04 INFO - Pipeline is PREROLLED ...
[task 2022-08-16T04:30:04.642Z] 04:30:04 INFO - Setting pipeline to PLAYING ...
[task 2022-08-16T04:30:04.642Z] 04:30:04 INFO - New clock: GstSystemClock
[task 2022-08-16T04:30:04.675Z] 04:30:04 INFO - Got EOS from element "pipeline0".
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - Execution ended after 0:00:00.033534708
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - Setting pipeline to PAUSED ...
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - Setting pipeline to READY ...
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - (gst-launch-1.0:5701): GStreamer-CRITICAL **: 04:30:04.675: gst_object_unref: assertion '((GObject *) object)->ref_count > 0' failed
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - Setting pipeline to NULL ...
[task 2022-08-16T04:30:04.677Z] 04:30:04 INFO - Freeing pipeline ...
[task 2022-08-16T04:30:04.729Z] 04:30:04 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-08-16T04:30:05.101Z] 04:30:05 INFO - PID 5723 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2022-08-16T04:30:05.217Z] 04:30:05 INFO - Increasing default timeout to 120 seconds
[task 2022-08-16T04:30:05.218Z] 04:30:05 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-08-16T04:30:05.219Z] 04:30:05 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-08-16T04:30:05.225Z] 04:30:05 INFO - MochitestServer : launching ['/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/tmphz41qu00.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 2022-08-16T04:30:05.225Z] 04:30:05 INFO - runtests.py | Server pid: 5729
[task 2022-08-16T04:30:05.233Z] 04:30:05 INFO - runtests.py | Websocket server pid: 5732
[task 2022-08-16T04:30:05.235Z] 04:30:05 INFO - INFO | runtests.py | TSan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2022-08-16T04:30:05.241Z] 04:30:05 INFO - runtests.py | SSL tunnel pid: 5736
[task 2022-08-16T04:30:05.745Z] 04:30:05 INFO - runtests.py | Running with scheme: http
[task 2022-08-16T04:30:05.746Z] 04:30:05 INFO - runtests.py | Running with e10s: True
[task 2022-08-16T04:30:05.746Z] 04:30:05 INFO - runtests.py | Running with fission: True
[task 2022-08-16T04:30:05.746Z] 04:30:05 INFO - runtests.py | Running with cross-origin iframes: False
[task 2022-08-16T04:30:05.746Z] 04:30:05 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2022-08-16T04:30:05.746Z] 04:30:05 INFO - runtests.py | Running with socketprocess_e10s: False
[task 2022-08-16T04:30:05.747Z] 04:30:05 INFO - runtests.py | Running tests: start.
[task 2022-08-16T04:30:05.747Z] 04:30:05 INFO -
[task 2022-08-16T04:30:05.763Z] 04:30:05 WARNING - Found 'firefox' running before starting test browser!
[task 2022-08-16T04:30:05.763Z] 04:30:05 WARNING - {'pid': 5659, 'cmdline': [], 'ppid': 1, 'name': 'firefox', 'username': 'worker'}
[task 2022-08-16T04:30:05.764Z] 04:30:05 WARNING - {'pid': 5662, 'cmdline': [], 'ppid': 1, 'name': 'firefox', 'username': 'worker'}
[task 2022-08-16T04:30:05.764Z] 04:30:05 WARNING -
[task 2022-08-16T04:30:05.764Z] 04:30:05 INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox -marionette -foreground -profile /tmp/tmphz41qu00.mozrunner
[task 2022-08-16T04:30:05.769Z] 04:30:05 INFO - runtests.py | Application pid: 5756
[task 2022-08-16T04:30:05.770Z] 04:30:05 INFO - TEST-INFO | started process GECKO(5756)
[task 2022-08-16T04:30:08.843Z] 04:30:08 INFO - GECKO(5756) | 1660624208842 Marionette INFO Marionette enabled
[task 2022-08-16T04:30:08.854Z] 04:30:08 INFO - GECKO(5756) | 1660624208852 Marionette TRACE Received observer notification final-ui-startup
[task 2022-08-16T04:30:08.864Z] 04:30:08 INFO - GECKO(5756) | 1660624208862 Marionette INFO Listening on port 2828
[task 2022-08-16T04:30:08.865Z] 04:30:08 INFO - GECKO(5756) | 1660624208863 Marionette DEBUG Marionette is listening
[task 2022-08-16T04:30:09.625Z] 04:30:09 INFO - GECKO(5756) | 1660624209624 Marionette DEBUG Accepted connection 0 from 127.0.0.1:48222
[task 2022-08-16T04:30:09.832Z] 04:30:09 INFO - GECKO(5756) | 1660624209831 Marionette DEBUG Closed connection 0
[task 2022-08-16T04:30:09.839Z] 04:30:09 INFO - GECKO(5756) | 1660624209838 Marionette DEBUG Accepted connection 1 from 127.0.0.1:48224
[task 2022-08-16T04:30:10.110Z] 04:30:10 INFO - GECKO(5756) | 1660624210109 Marionette DEBUG Closed connection 1
[task 2022-08-16T04:30:10.113Z] 04:30:10 INFO - GECKO(5756) | 1660624210111 Marionette DEBUG Accepted connection 2 from 127.0.0.1:48226
[task 2022-08-16T04:30:11.588Z] 04:30:11 INFO - GECKO(5756) | 1660624211585 Marionette DEBUG 2 -> [0,1,"WebDriver:NewSession",{"strictFileInteractability":true}]
[task 2022-08-16T04:30:11.613Z] 04:30:11 INFO - GECKO(5756) | 1660624211612 Marionette DEBUG Waiting for initial application window
[task 2022-08-16T04:30:16.146Z] 04:30:16 INFO - GECKO(5756) | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at /tmp/tmphz41qu00.mozrunner/search.json.mozlz4", (void 0)))
[task 2022-08-16T04:30:22.647Z] 04:30:22 INFO - GECKO(5756) | 1660624222646 Marionette TRACE Received observer notification browser-idle-startup-tasks-finished
[task 2022-08-16T04:30:22.689Z] 04:30:22 INFO - GECKO(5756) | 1660624222687 RemoteAgent TRACE [9] Document already finished loading: about:blank
[task 2022-08-16T04:30:22.755Z] 04:30:22 INFO - GECKO(5756) | 1660624222753 Marionette DEBUG 2 <- [1,1,null,{"sessionId":"a2e7e898-5719-4377-a7dc-fcd5d1e36554","capabilities":{"browserName":"firefox","browserVersion":"105.0 ... wnTimeout":360000,"moz:useNonSpecCompliantPointerOrigin":false,"moz:webdriverClick":true,"moz:windowless":false,"proxy":{}}}]
[task 2022-08-16T04:30:22.791Z] 04:30:22 INFO - GECKO(5756) | 1660624222789 Marionette DEBUG 2 -> [0,2,"Addon:Install",{"path":"/tmp/tmp6wc50uaj.zip","temporary":false}]
[task 2022-08-16T04:30:23.184Z] 04:30:23 INFO - GECKO(5756) | 1660624223182 Marionette DEBUG 2 <- [1,2,null,{"value":"special-powers@mozilla.org"}]
[task 2022-08-16T04:30:23.219Z] 04:30:23 INFO - GECKO(5756) | 1660624223218 Marionette DEBUG 2 -> [0,3,"Addon:Install",{"path":"/tmp/tmp5f5r7n89.zip","temporary":false}]
[task 2022-08-16T04:30:23.370Z] 04:30:23 INFO - GECKO(5756) | 1660624223369 Marionette DEBUG 2 <- [1,3,null,{"value":"mochikit@mozilla.org"}]
[task 2022-08-16T04:30:23.391Z] 04:30:23 INFO - GECKO(5756) | 1660624223390 Marionette DEBUG 2 -> [0,4,"Marionette:GetContext",{}]
[task 2022-08-16T04:30:23.392Z] 04:30:23 INFO - GECKO(5756) | 1660624223391 Marionette DEBUG 2 <- [1,4,null,{"value":"content"}]
[task 2022-08-16T04:30:23.398Z] 04:30:23 INFO - GECKO(5756) | 1660624223396 Marionette DEBUG 2 -> [0,5,"Marionette:SetContext",{"value":"chrome"}]
[task 2022-08-16T04:30:23.399Z] 04:30:23 INFO - GECKO(5756) | 1660624223398 Marionette DEBUG 2 <- [1,5,null,{"value":null}]
[task 2022-08-16T04:30:23.404Z] 04:30:23 INFO - GECKO(5756) | 1660624223403 Marionette DEBUG 2 -> [0,6,"WebDriver:ExecuteScript",{"script":"/* This Source Code Form is subject to the terms of the Mozilla Public\n * License, ... hrome","testUrl":"about:blank"}],"newSandbox":true,"sandbox":"default","line":2016,"filename":"tests/mochitest/runtests.py"}]
[task 2022-08-16T04:30:23.433Z] 04:30:23 INFO - GECKO(5756) | 1660624223431 Marionette TRACE [1] MarionetteCommands actor created for window id 2
[task 2022-08-16T04:30:23.585Z] 04:30:23 INFO - GECKO(5756) | 1660624223584 Marionette TRACE Received observer notification domwindowopened
[task 2022-08-16T04:30:23.611Z] 04:30:23 INFO - GECKO(5756) | 1660624223609 Marionette DEBUG 2 <- [1,6,null,{"value":null}]
[task 2022-08-16T04:30:23.629Z] 04:30:23 INFO - GECKO(5756) | 1660624223627 Marionette DEBUG 2 -> [0,7,"Marionette:SetContext",{"value":"content"}]
[task 2022-08-16T04:30:23.631Z] 04:30:23 INFO - GECKO(5756) | 1660624223629 Marionette DEBUG 2 <- [1,7,null,{"value":null}]
[task 2022-08-16T04:30:23.768Z] 04:30:23 INFO - GECKO(5756) | 1660624223766 Marionette DEBUG 2 -> [0,8,"WebDriver:DeleteSession",{}]
[task 2022-08-16T04:30:23.807Z] 04:30:23 INFO - GECKO(5756) | 1660624223805 Marionette DEBUG 2 <- [1,8,null,{"value":null}]
[task 2022-08-16T04:30:23.885Z] 04:30:23 INFO - runtests.py | Waiting for browser...
[task 2022-08-16T04:30:23.942Z] 04:30:23 INFO - GECKO(5756) | 1660624223941 Marionette DEBUG Closed connection 2
[task 2022-08-16T04:30:24.989Z] 04:30:24 INFO - TEST-START | toolkit/components/nimbus/test/browser/browser_experiment_evaluate_jexl.js
|
||
Updated•2 years ago
|
Group: core-security → network-core-security
Component: XPCOM → Networking: Cache
|
|
||
Comment 1•2 years ago
|
||
Nika, is nsLocalFile supposed to be threadsafe? My instinct here is that CacheFileIOManager should hold a lock when accessing mCacheDirectory, but I could be wrong. Thanks. I guess it does have threadsafe refcounting.
Flags: needinfo?(nika)
|
||
Comment 2•2 years ago
|
||
Yeah, the nsLocalFile type can't have its methods called from multiple threads concurrently IIRC - I don't think the caches deal with that well.
Flags: needinfo?(nika)
|
||
Updated•2 years ago
|
Keywords: csectype-race,
sec-moderate
|
Assignee | |
Updated•2 years ago
|
Assignee: nobody → valentin.gosu
Severity: -- → S3
Priority: -- → P1
Regressed by: 1705676
Whiteboard: [necko-triaged]
|
||
Comment 3•2 years ago
|
||
Set release status flags based on info from the regressing bug 1705676
status-firefox104:
--- → unaffected
status-firefox105:
--- → affected
status-firefox106:
--- → affected
status-firefox-esr102:
--- → unaffected
status-firefox-esr91:
--- → unaffected
|
|
Assignee | |
Comment 4•2 years ago
|
||
I wouldn't have expected GetParent to actually change the file 😮
It seems that this data race comes with some easter eggs.
In any case, this the feature is only enabled on Nightly + Windows for now.
I think the easiest way to fix it is to clone the file before calling GetParent.
|
|
Assignee | |
Comment 5•2 years ago
|
||
|
|
||
Updated•2 years ago
|
|
|
||
Updated•2 years ago
|
Whiteboard: [necko-triaged] → [necko-triaged][Windows and Nightly only]
|
||
Updated•2 years ago
|
Attachment #9291939 -
Attachment description: WIP: Bug 1785018 - Avoid data race when calling GetParent for nsLocalFileUnix r=#necko,nika → Bug 1785018 - Avoid data race when calling GetParent for nsLocalFileUnix r=#necko
|
||
Comment 6•2 years ago
|
||
Avoid data race when calling GetParent for nsLocalFileUnix r=necko-reviewers,kershaw
https://hg.mozilla.org/integration/autoland/rev/7a283a508e8f6a7d6b605eeafb0f3fb6f885cf69
https://hg.mozilla.org/mozilla-central/rev/7a283a508e8f
Group: network-core-security → core-security-release
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 106 Branch
|
||
Updated•2 years ago
|
Whiteboard: [necko-triaged][Windows and Nightly only] → [necko-triaged][Windows and Nightly only][post-critsmash-triage]
|
|
||
Updated•2 years ago
|
Flags: qe-verify-
|
|
||
Updated•1 year ago
|
Group: core-security-release
You need to log in
before you can comment on or make changes to this bug.
Description
•