Closed Bug 1750855 Opened 3 years ago Closed 3 years ago

Perma valgrind-test | Valgrind timed out (reached 2400 second limit)

Categories

(Firefox Build System :: Task Configuration, defect, P5)

Product:
Firefox Build System
Component:
Task Configuration
Type:
defect

Tracking

(firefox-esr91 unaffected, firefox96 unaffected, firefox97 unaffected, firefox98 fixed)

Status:
RESOLVED FIXED
Milestone:
98 Branch
Tracking Flags:
Tracking Status
firefox-esr91 --- unaffected
firefox96 --- unaffected
firefox97 --- unaffected
firefox98 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: glandium)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file, 1 obsolete file)

Bug 1750855 - Don't valgrind dbus-launch and its subprocesses.
48 bytes, text/x-phabricator-request
Details | Review

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=364528826&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EHqrVVpYT-OqLLXWcw3duw/runs/1/artifacts/public/logs/live_backing.log

[task 2022-01-18T20:02:51.308Z] 20:02:51     INFO -   1:35.01 --11248-- failed to stat64/stat this file
[task 2022-01-18T20:02:51.751Z] 20:02:51     INFO -   1:35.46 --11248-- WARNING: Serious error when reading debug info
[task 2022-01-18T20:02:51.751Z] 20:02:51     INFO -   1:35.46 --11248-- When reading debug info from /memfd:mozilla-ipc (deleted):
[task 2022-01-18T20:02:51.751Z] 20:02:51     INFO -   1:35.46 --11248-- failed to stat64/stat this file
[task 2022-01-18T20:02:53.153Z] 20:02:53     INFO -   1:36.86 --11248-- REDIR: 0x4cb1920 (libc.so.6:__strcpy_chk) redirected to 0x4843e80 (__strcpy_chk)
[task 2022-01-18T20:02:53.178Z] 20:02:53     INFO -   1:36.88 --11248-- Reading syms from /builds/worker/workspace/obj-build/security/nss/lib/softoken/softoken_softokn3/libsoftokn3.so
[task 2022-01-18T20:02:53.178Z] 20:02:53     INFO -   1:36.88 --11248-- ELF section outside all mapped regions
[task 2022-01-18T20:02:53.179Z] 20:02:53     INFO -   1:36.88 --11248-- Reading syms from /builds/worker/workspace/obj-build/security/nss/lib/softoken/softoken_softokn3/libsoftokn3.so
[task 2022-01-18T20:02:53.212Z] 20:02:53     INFO -   1:36.92 --11248-- REDIR: 0x4d07380 (libc.so.6:__strncpy_avx2) redirected to 0x483fa70 (strncpy)
[task 2022-01-18T20:02:53.236Z] 20:02:53     INFO -   1:36.94 --11248-- Reading syms from /builds/worker/workspace/obj-build/security/nss/lib/freebl/freebl_freeblpriv3/libfreeblpriv3.so
[task 2022-01-18T20:02:53.571Z] 20:02:53     INFO -   1:37.28 --11248-- Reading syms from /builds/worker/workspace/obj-build/security/manager/ssl/ipcclientcerts/dynamic-library/libipcclientcerts.so
[task 2022-01-18T20:02:53.585Z] 20:02:53     INFO -   1:37.29 --11248-- REDIR: 0x4d07ce0 (libc.so.6:__stpcpy_avx2) redirected to 0x4842c40 (stpcpy)
[task 2022-01-18T20:06:46.011Z] 20:06:46     INFO -   5:29.72 ==11111== Warning: set address range perms: large range [0x59c88000, 0xc314b000) (defined)
[task 2022-01-18T20:06:49.858Z] 20:06:49     INFO -   5:33.56 ==11111== Warning: set address range perms: large range [0x59c88000, 0xc314b000) (noaccess)
[task 2022-01-18T20:07:52.966Z] 20:07:52     INFO -   6:36.67 --11248-- REDIR: 0x491d4c0 (libstdc++.so.6:operator delete[](void*)) redirected to 0x483d670 (operator delete[](void*))
[task 2022-01-18T20:41:18.006Z] 20:41:18     INFO -  40:01.71 TEST-PASS | valgrind-test | valgrind found no errors
[task 2022-01-18T20:41:18.006Z] 20:41:18     INFO -  40:01.71 TEST-UNEXPECTED-FAIL | valgrind-test | Valgrind timed out (reached 2400 second limit)
[task 2022-01-18T20:41:18.293Z] 20:41:18     INFO -  40:02.00 ==11248==
[task 2022-01-18T20:41:18.293Z] 20:41:18     INFO -  40:02.00 ==11248== Process terminating with default action of signal 15 (SIGTERM)
[task 2022-01-18T20:41:18.293Z] 20:41:18     INFO -  40:02.00 ==11248==    at 0x4C993FF: poll+79 (poll.c:29)
[task 2022-01-18T20:41:18.293Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD92DB48: PollWrapper(_GPollFD*, unsigned int, int)+216 (checkouts/gecko/widget/gtk/nsAppShell.cpp:62)
[task 2022-01-18T20:41:18.293Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0x6CD50AD: g_main_context_poll (gmain.c:4422)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0x6CD50AD: g_main_context_iterate.constprop.0+381 (gmain.c:4114)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0x6CD51CE: g_main_context_iteration+46 (gmain.c:4184)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD92DCDF: nsAppShell::ProcessNextNativeEvent(bool)+15 (checkouts/gecko/widget/gtk/nsAppShell.cpp:352)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD8B51C7: DoProcessNextNativeEvent (checkouts/gecko/widget/nsBaseAppShell.cpp:120)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD8B51C7: nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool)+439 (checkouts/gecko/widget/nsBaseAppShell.cpp:259)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD8B52AC: non-virtual thunk to nsBaseAppShell::OnProcessNextEvent(nsIThreadInternal*, bool)+12 (checkouts/gecko/widget/nsBaseAppShell.cpp:0)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xAF943DD: nsThread::ProcessNextEvent(bool, bool*)+557 (checkouts/gecko/xpcom/threads/nsThread.cpp:1111)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xAF98A97: NS_ProcessNextEvent(nsIThread*, bool)+71 (checkouts/gecko/xpcom/threads/nsThreadUtils.cpp:467)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB58BF9D: mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*)+205 (checkouts/gecko/ipc/glue/MessagePump.cpp:107)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: RunInternal (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: RunHandler (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: MessageLoop::Run()+85 (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xD8B4EC8: nsBaseAppShell::Run()+40 (checkouts/gecko/widget/nsBaseAppShell.cpp:137)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xED71263: XRE_RunAppShell()+67 (checkouts/gecko/toolkit/xre/nsEmbedFunctions.cpp:870)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: RunInternal (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:331)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: RunHandler (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:324)
[task 2022-01-18T20:41:18.298Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xB53C725: MessageLoop::Run()+85 (checkouts/gecko/ipc/chromium/src/base/message_loop.cc:306)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0xED71075: XRE_InitChildProcess(int, char**, XREChildData const*)+1045 (checkouts/gecko/toolkit/xre/nsEmbedFunctions.cpp:707)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0x12B573: content_process_main (checkouts/gecko/browser/app/../../ipc/contentproc/plugin-container.cpp:57)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==    by 0x12B573: main+707 (checkouts/gecko/browser/app/nsBrowserApp.cpp:327)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248== HEAP SUMMARY:
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==     in use at exit: 857,329 bytes in 4,674 blocks
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==   total heap usage: 6,916 allocs, 2,242 frees, 1,017,629 bytes allocated
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248==
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11248== Searching for pointers to 4,674 not-freed blocks
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111== Process terminating with default action of signal 15 (SIGTERM)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    at 0x4864FE1: raise+321 (raise.c:51)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xED5C494: nsProfileLock::FatalSignalHandler(int, siginfo_t*, void*)+244 (checkouts/gecko/toolkit/profile/nsProfileLock.cpp:174)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x486513F: ??? (in /lib/x86_64-linux-gnu/libpthread-2.31.so)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x4C993FE: poll+78 (poll.c:29)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6D24265: g_spawn_sync+501 (gspawn.c:454)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6D249E6: g_spawn_command_line_sync+134 (gspawn.c:944)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6ADBF23: get_session_address_dbus_launch+179 (gdbusaddress.c:1140)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6ADDB7D: get_session_address_platform_specific (gdbusaddress.c:1255)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6ADDB7D: g_dbus_address_get_for_bus_sync+813 (gdbusaddress.c:1368)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6AE9F55: get_uninitialized_connection+117 (gdbusconnection.c:7249)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6AEFB65: g_bus_get_sync+85 (gdbusconnection.c:7344)
[task 2022-01-18T20:41:18.299Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6AF918D: initable_init+109 (gdbusproxy.c:1882)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6A5A8E1: g_initable_new_valist+145 (ginitable.c:248)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6A5A998: g_initable_new+152 (ginitable.c:162)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0x6AFAC0C: g_dbus_proxy_new_for_bus_sync+236 (gdbusproxy.c:2218)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xD94CC3A: nsLookAndFeel::nsLookAndFeel()+1178 (checkouts/gecko/widget/gtk/nsLookAndFeel.cpp:179)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xD8CE3E9: nsXPLookAndFeel::GetInstance()+329 (checkouts/gecko/widget/nsXPLookAndFeel.cpp:351)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xD8D02A0: mozilla::LookAndFeel::GetThemeInfo(nsTSubstring<char>&)+16 (checkouts/gecko/widget/nsXPLookAndFeel.cpp:1368)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF1951E: nsSystemInfo::Init()+1166 (checkouts/gecko/xpcom/base/nsSystemInfo.cpp:1047)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF70147: mozilla::xpcom::CreateInstanceImpl(mozilla::xpcom::ModuleID, nsISupports*, nsID const&, void**)+25207 (StaticComponents.cpp:10475)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF79F07: CreateInstance (checkouts/gecko/xpcom/components/nsComponentManager.cpp:181)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF79F07: nsComponentManagerImpl::GetServiceLocked(mozilla::Maybe<mozilla::MonitorAutoLock>&, (anonymous namespace)::EntryWrapper&, nsID const&, void**)+1063 (checkouts/gecko/xpcom/components/nsComponentManager.cpp:1288)
[task 2022-01-18T20:41:18.300Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF7A45B: nsComponentManagerImpl::GetService(mozilla::xpcom::ModuleID, nsID const&, void**)+459 (checkouts/gecko/xpcom/components/nsComponentManager.cpp:1378)
[task 2022-01-18T20:41:18.301Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF71081: mozilla::xpcom::GetServiceHelper::operator()(nsID const&, void**) const+33 (StaticComponents.cpp:12404)
[task 2022-01-18T20:41:18.301Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xAF0073A: nsCOMPtr_base::assign_from_helper(nsCOMPtr_helper const&, nsID const&)+42 (checkouts/gecko/xpcom/base/nsCOMPtr.cpp:109)
[task 2022-01-18T20:41:18.301Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xB9AA056: xpc::Services_Resolve(JSContext*, JS::Handle<JSObject*>, JS::Handle<JS::PropertyKey>, bool*)+630 (dist/include/nsCOMPtr.h:999)
[task 2022-01-18T20:41:18.301Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xF0071ED: js::NativeGetProperty(JSContext*, JS::Handle<js::NativeObject*>, JS::Handle<JS::Value>, JS::Handle<JS::PropertyKey>, JS::MutableHandle<JS::Value>)+1277 (checkouts/gecko/js/src/vm/NativeObject-inl.h:640)
[task 2022-01-18T20:41:18.302Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE45E3E: js::GetProperty(JSContext*, JS::Handle<JS::Value>, JS::Handle<js::PropertyName*>, JS::MutableHandle<JS::Value>)+1038 (checkouts/gecko/js/src/vm/ObjectOperations-inl.h:120)
[task 2022-01-18T20:41:18.302Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE3A957: GetPropertyOperation (checkouts/gecko/js/src/vm/Interpreter.cpp:208)
[task 2022-01-18T20:41:18.302Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE3A957: Interpret(JSContext*, js::RunState&)+26343 (checkouts/gecko/js/src/vm/Interpreter.cpp:2973)
[task 2022-01-18T20:41:18.302Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE34231: js::RunScript(JSContext*, js::RunState&)+657 (checkouts/gecko/js/src/vm/Interpreter.cpp:394)
[task 2022-01-18T20:41:18.303Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE43167: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason)+1591 (checkouts/gecko/js/src/vm/Interpreter.cpp:544)
[task 2022-01-18T20:41:18.303Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE4392C: js::Call(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, js::AnyInvokeArgs const&, JS::MutableHandle<JS::Value>, js::CallReason)+156 (checkouts/gecko/js/src/vm/Interpreter.cpp:572)
[task 2022-01-18T20:41:18.303Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE441D6: js::CallGetter(JSContext*, JS::Handle<JS::Value>, JS::Handle<JS::Value>, JS::MutableHandle<JS::Value>)+150 (checkouts/gecko/js/src/vm/Interpreter.cpp:715)
[task 2022-01-18T20:41:18.303Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xF0075B1: js::NativeGetProperty(JSContext*, JS::Handle<js::NativeObject*>, JS::Handle<JS::Value>, JS::Handle<JS::PropertyKey>, JS::MutableHandle<JS::Value>)+2241 (checkouts/gecko/js/src/vm/NativeObject.cpp:1970)
[task 2022-01-18T20:41:18.304Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE45E3E: js::GetProperty(JSContext*, JS::Handle<JS::Value>, JS::Handle<js::PropertyName*>, JS::MutableHandle<JS::Value>)+1038 (checkouts/gecko/js/src/vm/ObjectOperations-inl.h:120)
[task 2022-01-18T20:41:18.304Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE3A957: GetPropertyOperation (checkouts/gecko/js/src/vm/Interpreter.cpp:208)
[task 2022-01-18T20:41:18.304Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE3A957: Interpret(JSContext*, js::RunState&)+26343 (checkouts/gecko/js/src/vm/Interpreter.cpp:2973)
[task 2022-01-18T20:41:18.304Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE34231: js::RunScript(JSContext*, js::RunState&)+657 (checkouts/gecko/js/src/vm/Interpreter.cpp:394)
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.00 ==11111==    by 0xEE43167: js::InternalCallOrConstruct(JSContext*, JS::CallArgs const&, js::MaybeConstruct, js::CallReason)+1591 (checkouts/gecko/js/src/vm/Interpreter.cpp:544)
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.00 ==11111==
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.01 ==11111== HEAP SUMMARY:
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.01 ==11111==     in use at exit: 27,623,405 bytes in 76,025 blocks
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.01 ==11111==   total heap usage: 303,999 allocs, 227,974 frees, 85,130,883 bytes allocated
[task 2022-01-18T20:41:18.305Z] 20:41:18     INFO -  40:02.01 ==11111==
[task 2022-01-18T20:41:18.319Z] 20:41:18     INFO -  40:02.02 ==11248== Checked 12,499,976 bytes
[task 2022-01-18T20:41:18.319Z] 20:41:18     INFO -  40:02.02 ==11248==
[task 2022-01-18T20:41:18.331Z] 20:41:18     INFO -  40:02.04 ==11111== Searching for pointers to 75,534 not-freed blocks
[task 2022-01-18T20:41:18.696Z] 20:41:18     INFO -  40:02.40 ==11248== LEAK SUMMARY:
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==    definitely lost: 0 bytes in 0 blocks
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==    indirectly lost: 0 bytes in 0 blocks
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==      possibly lost: 158,639 bytes in 2,389 blocks
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==    still reachable: 685,170 bytes in 2,272 blocks
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==                       of which reachable via heuristic:
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==                         stdstring          : 1,554 bytes in 41 blocks
[task 2022-01-18T20:41:18.697Z] 20:41:18     INFO -  40:02.40 ==11248==                         newarray           : 1,536 bytes in 16 blocks
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248==                         multipleinheritance: 1,120 bytes in 4 blocks
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248==         suppressed: 13,520 bytes in 13 blocks
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248== Reachable blocks (those to which a pointer was found) are not shown.
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248== To see them, rerun with: --leak-check=full --show-leak-kinds=all
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248==
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 ==11248== ERROR SUMMARY: 1731 errors from 1731 contexts (suppressed: 3 from 3)
[task 2022-01-18T20:41:18.698Z] 20:41:18     INFO -  40:02.40 --11248--
[task 2022-01-18T20:41:18.699Z] 20:41:18     INFO -  40:02.40 --11248-- used_suppression:      5 dtv-addr-init /usr/libexec/valgrind/default.supp:1468 suppressed: 13,328 bytes in 7 blocks
[task 2022-01-18T20:41:18.699Z] 20:41:18     INFO -  40:02.40 --11248-- used_suppression:      3 See bug 793535 /builds/worker/checkouts/gecko/build/valgrind/cross-architecture.sup:74 suppressed: 160 bytes in 5 blocks
[task 2022-01-18T20:41:18.699Z] 20:41:18     INFO -  40:02.40 --11248-- used_suppression:      1 Bug 794372 /builds/worker/checkouts/gecko/build/valgrind/cross-architecture.sup:121 suppressed: 32 bytes in 1 blocks
[task 2022-01-18T20:41:18.699Z] 20:41:18     INFO -  40:02.40 ==11248==
[task 2022-01-18T20:41:18.699Z] 20:41:18     INFO -  40:02.40 ==11248== ERROR SUMMARY: 1731 errors from 1731 contexts (suppressed: 3 from 3)
[task 2022-01-18T20:41:19.023Z] 20:41:19     INFO -  40:02.73 ==11111== Checked 374,280,768 bytes
[task 2022-01-18T20:41:19.024Z] 20:41:19     INFO -  40:02.73 ==11111==
[task 2022-01-18T20:41:19.405Z] 20:41:19    ERROR - Return code: 2
[task 2022-01-18T20:41:19.405Z] 20:41:19  WARNING - setting return code to 2
[task 2022-01-18T20:41:19.405Z] 20:41:19    FATAL - 'mach valgrind-test' did not run successfully. Please check log for errors
Summary: High frequency valgrind-test | Valgrind timed out (reached 2400 second limit) → Perma valgrind-test | Valgrind timed out (reached 2400 second limit)
Regressed by: 1734934
Has Regression Range: --- → yes
Blocks: 1750599

Hmm is it crashing inside libpthreads?

It gets stuck spawning dbus, apparently.

(I'm doing a try run with that dbus debugging enabled)

I can dig more, but today I'm traveling so can't do lots of valgrind
builds. Let's unblock valgrind jobs by disabling the regressing bug by
default there.

Assignee: nobody → emilio
Status: NEW → ASSIGNED

Ah, sorry, was already writing comment 5. We can hold off on that if you're actively debugging it.

Assignee: emilio → nobody
Status: ASSIGNED → NEW

For some reason, the execution of dbus-launch causes problems after bug
1734934, even though we were executing dbus-launch before. We don't
actually care about valgrind'ing its execution, though, because it's a
system program.

Assignee: nobody → mh+mozilla
Status: NEW → ASSIGNED

Thanks Mike!

Attachment #9259641 - Attachment is obsolete: true

FWIW, it looks like what makes the difference is that this dbus-launch now happens on the main thread, when it used to happen on a separate thread. Presumably, that thread was stuck too, but that didn't cause problems. That raises the question whether we'd want to avoid that dbus-launch on the main thread in a followup.

Flags: needinfo?(emilio)

Hmm, that might be worth trying but at the end of the day we need the value synchronously on the main thread (or read it async but then the UI might flicker on startup which is not great).

We could try to connect to dbus at soon as the process starts up on another thread, and then wait for that in the main thread, but without profiles that say this is or could be a problem I'm not sure it'd be worth the complexity, wdyt?

Flags: needinfo?(emilio)

Set release status flags based on info from the regressing bug 1734934

status-firefox96: --- → unaffected
status-firefox97: --- → unaffected
status-firefox98: --- → affected
status-firefox-esr91: --- → unaffected

Taking a step back, it probably doesn't matter because in the vast majority of cases dbus is already running, its session address is in the environment, and dbus-launch is not called.

Pushed by mh@glandium.org: https://hg.mozilla.org/integration/autoland/rev/0ada9afb6c54 Don't valgrind dbus-launch and its subprocesses. r=firefox-build-system-reviewers,mhentges

https://hg.mozilla.org/mozilla-central/rev/0ada9afb6c54

Status: ASSIGNED → RESOLVED
Closed: 3 years ago
status-firefox98: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 98 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: