Closed Bug 1703565 Opened 5 years ago Closed 1 year ago

Intermittent Android xpcshell | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex]

Categories

(Core :: XPCOM, defect)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: crash, intermittent-failure)

Crash Data

Filed by: smolnar [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335709383&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OMdJBcUdR0q69oWPXtc-8w/runs/0/artifacts/public/logs/live_backing.log


INFO -  TEST-START | js/xpconnect/tests/unit/test_watchdog_disable.js
[task 2021-04-07T13:37:20.368Z] 13:37:20     INFO -  adb launch_application: am startservice -W -n 'org.mozilla.geckoview.test/org.mozilla.geckoview.test.XpcshellTestRunnerService$i2' -a android.intent.action.MAIN --es arg8 'const _MOZINFO_JS_PATH = "/data/local/tmp/test_root/xpc/p/b62f0899-afd0-4ebf-ad5f-0f084c6d2c00/mozinfo.json";' --es arg9 -e --es arg0 -g --es arg1 /data/local/tmp/test_root/xpcb --es arg2 --greomni --es arg3 /data/local/tmp/test_root/xpcb/geckoview-androidTest.apk --es arg4 -m --es arg5 -e --es arg6 'const _HEAD_JS_PATH = "/data/local/tmp/test_root/xpc/head.js";' --es arg7 -e --es arg26 '_execute_test(); quit(0);' --es arg25 -e --es arg24 'const _TEST_NAME = "js/xpconnect/tests/unit/test_watchdog_enable.js";' --es arg23 -e --es arg22 'const _TEST_FILE = ["test_watchdog_enable.js"];' --es arg21 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/js/xpconnect/tests/unit";' --ez use_multiprocess True --es env20 TMPDIR=/data/local/tmp/test_root/xpc/p/b62f0899-afd0-4ebf-ad5f-0f084c6d2c00 --es arg12 'const _TESTING_MODULES_DIR = "/data/local/tmp/test_root/xpc/m";' --es arg13 -f --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/user.js";' --es arg11 -e --es arg16 'const _HEAD_FILES = ["/data/local/tmp/test_root/xpc/js/xpconnect/tests/unit/head_watchdog.js"];' --es arg17 -e --es arg14 /data/local/tmp/test_root/xpc/head.js --es arg15 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es env9 MOZHTTP2_PORT=46773 --es env8 GRE_HOME=/data/local/tmp/test_root/xpcb --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-c2904532-2bb5-4469-92df-5dbd184740b5.log --es env3 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/b62f0899-afd0-4ebf-ad5f-0f084c6d2c00 --es env2 XPCOM_DEBUG_BREAK=stack-and-abort --es env1 MOZ_DISABLE_SOCKET_PROCESS_SANDBOX=1 --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env6 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/29e503f3-bb76-47ef-b6af-364eefe10d08 --es env5 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env4 MOZ_WEBRENDER=0 --es env19 MOZ_ANDROID_CPU_ABI=x86_64 --es env18 HOME=/data/local/tmp/test_root/xpc/p --es env17 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env16 MOZ_DEVELOPER_REPO_DIR=/builds/worker/checkouts/gecko --es env15 MOZ_CRASHREPORTER_NO_REPORT=1 --es env14 MOZNODE_EXEC_PORT=46373 --es env13 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/b62f0899-afd0-4ebf-ad5f-0f084c6d2c00 --es env12 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env11 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env10 MOZ_DISABLE_SOCKET_PROCESS=1
[task 2021-04-07T13:37:20.376Z] 13:37:20     INFO -  adb launch_application: am startservice -W -n 'org.mozilla.geckoview.test/org.mozilla.geckoview.test.XpcshellTestRunnerService$i3' -a android.intent.action.MAIN --es arg8 'const _MOZINFO_JS_PATH = "/data/local/tmp/test_root/xpc/p/aed6d8c6-c66b-438e-99ce-f2115474e6c8/mozinfo.json";' --es arg9 -e --es arg0 -g --es arg1 /data/local/tmp/test_root/xpcb --es arg2 --greomni --es arg3 /data/local/tmp/test_root/xpcb/geckoview-androidTest.apk --es arg4 -m --es arg5 -e --es arg6 'const _HEAD_JS_PATH = "/data/local/tmp/test_root/xpc/head.js";' --es arg7 -e --es arg26 '_execute_test(); quit(0);' --es arg25 -e --es arg24 'const _TEST_NAME = "js/xpconnect/tests/unit/test_watchdog_disable.js";' --es arg23 -e --es arg22 'const _TEST_FILE = ["test_watchdog_disable.js"];' --es arg21 -e --es arg20 'const _TEST_CWD = "/data/local/tmp/test_root/xpc/js/xpconnect/tests/unit";' --ez use_multiprocess True --es env20 TMPDIR=/data/local/tmp/test_root/xpc/p/aed6d8c6-c66b-438e-99ce-f2115474e6c8 --es arg12 'const _TESTING_MODULES_DIR = "/data/local/tmp/test_root/xpc/m";' --es arg13 -f --es arg10 'const _PREFS_FILE = "/data/local/tmp/test_root/xpc/user.js";' --es arg11 -e --es arg16 'const _HEAD_FILES = ["/data/local/tmp/test_root/xpc/js/xpconnect/tests/unit/head_watchdog.js"];' --es arg17 -e --es arg14 /data/local/tmp/test_root/xpc/head.js --es arg15 -e --es arg18 'const _JSDEBUGGER_PORT = 0;' --es arg19 -e --es env9 MOZHTTP2_PORT=46773 --es env8 GRE_HOME=/data/local/tmp/test_root/xpcb --es out_file /data/local/tmp/test_root/xpc/logs/xpcshell-724ccdf9-99f5-4167-af51-67ac42532b88.log --es env3 XPCSHELL_MINIDUMP_DIR=/data/local/tmp/test_root/xpc/minidumps/aed6d8c6-c66b-438e-99ce-f2115474e6c8 --es env2 XPCOM_DEBUG_BREAK=stack-and-abort --es env1 MOZ_DISABLE_SOCKET_PROCESS_SANDBOX=1 --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_ANDROID_DATA_DIR=/data/local/tmp/test_root/xpcb --es env6 XPCSHELL_TEST_TEMP_DIR=/data/local/tmp/test_root/xpc/tmp/9f1627ad-dda2-4fa2-8fb0-074f31f5e6e2 --es env5 MOZ_DISABLE_CONTENT_SANDBOX=1 --es env4 MOZ_WEBRENDER=0 --es env19 MOZ_ANDROID_CPU_ABI=x86_64 --es env18 HOME=/data/local/tmp/test_root/xpc/p --es env17 MOZ_LINKER_CACHE=/data/local/tmp/test_root/xpcb --es env16 MOZ_DEVELOPER_REPO_DIR=/builds/worker/checkouts/gecko --es env15 MOZ_CRASHREPORTER_NO_REPORT=1 --es env14 MOZNODE_EXEC_PORT=46373 --es env13 XPCSHELL_TEST_PROFILE_DIR=/data/local/tmp/test_root/xpc/p/aed6d8c6-c66b-438e-99ce-f2115474e6c8 --es env12 LD_LIBRARY_PATH=/data/local/tmp/test_root/xpcb --es env11 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env10 MOZ_DISABLE_SOCKET_PROCESS=1
[task 2021-04-07T13:37:20.486Z] 13:37:20     INFO -  remotexpcshelltests.py | Launched Test App PID=11184
[task 2021-04-07T13:37:20.627Z] 13:37:20     INFO -  remotexpcshelltests.py | Launched Test App PID=11236
[task 2021-04-07T13:37:20.656Z] 13:37:20     INFO -  remotexpcshelltests.py | Launched Test App PID=11261
[task 2021-04-07T13:37:21.128Z] 13:37:21     INFO -  remotexpcshelltests.py | Application ran for: 0:00:00.914940
[task 2021-04-07T13:37:21.216Z] 13:37:21     INFO -  TEST-PASS | js/xpconnect/tests/unit/test_sandbox_name.js | took 1004ms
[task 2021-04-07T13:37:21.316Z] 13:37:21     INFO -  Cleaning up profile for /builds/worker/workspace/build/tests/xpcshell/tests/js/xpconnect/tests/unit/test_sandbox_name.js folder: /data/local/tmp/test_root/xpc/p/5a2b3446-b698-4f20-828e-d46970de5c9f
[task 2021-04-07T13:37:22.143Z] 13:37:22     INFO -  mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/7097499c-c6c8-a136-66ea-2924b84c336a.dmp
[task 2021-04-07T13:37:22.143Z] 13:37:22     INFO -  mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/7097499c-c6c8-a136-66ea-2924b84c336a.extra
[task 2021-04-07T13:37:22.153Z] 13:37:22  WARNING -  PROCESS-CRASH | js/xpconnect/tests/unit/test_crypto.js | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()]
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -  Mozilla crash reason: MOZ_ASSERT(!mOwningThread) (destroying a still-owned lock!)
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -  Crash dump filename: /tmp/tmpEOMguY/7097499c-c6c8-a136-66ea-2924b84c336a.dmp
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -  Operating system: Android
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -                    0.0.0 Linux 3.10.0+ #260 SMP PREEMPT Fri May 19 12:48:14 PDT 2017 x86_64
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -  CPU: amd64
[task 2021-04-07T13:37:22.154Z] 13:37:22     INFO -       family 6 model 6 stepping 3
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -       4 CPUs
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -  GPU: UNKNOWN
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -  Crash reason:  SIGSEGV /SEGV_MAPERR
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -  Crash address: 0x0
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -  Process uptime: not available
[task 2021-04-07T13:37:22.155Z] 13:37:22     INFO -  Thread 11 (crashed)
INFO -   0  libxul.so!mozilla::OffTheBooksMutex::~OffTheBooksMutex() [Mutex.h:269819a25f93255894887689cac0df8e7dbf3cb5 : 55 + 0x27]
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -      rax = 0x00007b6102b0437a   rdx = 0x0000000000000004
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -      rcx = 0x00007b61075dcdf8   rbx = 0x00007b61070226f0
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -      rsi = 0x00007b6107ee1f10   rdi = 0x000000000000001b
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3040   rsp = 0x00007b6107ee3030
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -       r8 = 0x000000000000ffff    r9 = 0x0000000000000000
[task 2021-04-07T13:37:22.156Z] 13:37:22     INFO -      r10 = 0x00007b6122ef03d0   r11 = 0x0000000000000246
[task 2021-04-07T13:37:22.157Z] 13:37:22     INFO -      r12 = 0x00007b6106994000   r13 = 0x0000000000000010
[task 2021-04-07T13:37:22.157Z] 13:37:22     INFO -      r14 = 0x00007b61070226e8   r15 = 0x00007b6107022790
[task 2021-04-07T13:37:22.157Z] 13:37:22     INFO -      rip = 0x00007b60fcc01ec1
[task 2021-04-07T13:37:22.157Z] 13:37:22     INFO -      Found by: given as instruction pointer in context
[task 2021-04-07T13:37:22.157Z] 13:37:22     INFO -   1  libxul.so!TimerThread::~TimerThread() [TimerThread.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 46 + 0x14]
[task 2021-04-07T13:37:22.158Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3070   rsp = 0x00007b6107ee3050
[task 2021-04-07T13:37:22.158Z] 13:37:22     INFO -      rip = 0x00007b60fcd2b944
[task 2021-04-07T13:37:22.158Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.158Z] 13:37:22     INFO -   2  libxul.so!TimerThread::~TimerThread() [TimerThread.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 42 + 0x11]
[task 2021-04-07T13:37:22.159Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3090   rsp = 0x00007b6107ee3080
[task 2021-04-07T13:37:22.159Z] 13:37:22     INFO -      rip = 0x00007b60fcd2b991
[task 2021-04-07T13:37:22.159Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.159Z] 13:37:22     INFO -   3  libxul.so!mozilla::Runnable::Release() [nsThreadUtils.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 68 + 0x53]
[task 2021-04-07T13:37:22.159Z] 13:37:22     INFO -      rbp = 0x00007b6107ee30b0   rsp = 0x00007b6107ee30a0
[task 2021-04-07T13:37:22.160Z] 13:37:22     INFO -      rip = 0x00007b60fcd2abf9
[task 2021-04-07T13:37:22.160Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.160Z] 13:37:22     INFO -   4  libxul.so!nsTArray_Impl<nsMaybeWeakPtr<nsIObserver>, nsTArrayInfallibleAllocator>::DestructRange(unsigned long, unsigned long) [nsTArray.h:269819a25f93255894887689cac0df8e7dbf3cb5 : 2404 + 0x8]
[task 2021-04-07T13:37:22.160Z] 13:37:22     INFO -      rbp = 0x00007b6107ee30d0   rsp = 0x00007b6107ee30c0
[task 2021-04-07T13:37:22.160Z] 13:37:22     INFO -      rip = 0x00007b60fccb55be
[task 2021-04-07T13:37:22.161Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.161Z] 13:37:22     INFO -   5  libxul.so!nsTArray_Impl<nsMaybeWeakPtr<nsIObserver>, nsTArrayInfallibleAllocator>::ClearAndRetainStorage() [nsTArray.h:269819a25f93255894887689cac0df8e7dbf3cb5 : 1482 + 0x9]
[task 2021-04-07T13:37:22.161Z] 13:37:22     INFO -      rbp = 0x00007b6107ee30f0   rsp = 0x00007b6107ee30e0
[task 2021-04-07T13:37:22.161Z] 13:37:22     INFO -      rip = 0x00007b60fccc098a
[task 2021-04-07T13:37:22.161Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.162Z] 13:37:22     INFO -   6  libxul.so!nsTArray_Impl<nsMaybeWeakPtr<nsIObserver>, nsTArrayInfallibleAllocator>::~nsTArray_Impl() [nsTArray.h:269819a25f93255894887689cac0df8e7dbf3cb5 : 1037 + 0x8]
[task 2021-04-07T13:37:22.162Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3110   rsp = 0x00007b6107ee3100
[task 2021-04-07T13:37:22.162Z] 13:37:22     INFO -      rip = 0x00007b60fccc0958
[task 2021-04-07T13:37:22.162Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.162Z] 13:37:22     INFO -   7  libxul.so!nsObserverList::~nsObserverList() [nsObserverList.h:269819a25f93255894887689cac0df8e7dbf3cb5 : 32 + 0x24]
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3130   rsp = 0x00007b6107ee3120
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -      rip = 0x00007b60fccc092e
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -   8  libxul.so!PLDHashTable::~PLDHashTable() [PLDHashTable.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 291 + 0x2b]
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3180   rsp = 0x00007b6107ee3140
[task 2021-04-07T13:37:22.163Z] 13:37:22     INFO -      rip = 0x00007b60fcca9592
[task 2021-04-07T13:37:22.164Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.164Z] 13:37:22     INFO -   9  libxul.so!PLDHashTable::ClearAndPrepareForLength(unsigned int) [PLDHashTable.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 306 + 0x5]
[task 2021-04-07T13:37:22.164Z] 13:37:22     INFO -      rbp = 0x00007b6107ee31b0   rsp = 0x00007b6107ee3190
[task 2021-04-07T13:37:22.164Z] 13:37:22     INFO -      rip = 0x00007b60fcca95fc
[task 2021-04-07T13:37:22.164Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -  10  libxul.so!mozilla::ShutdownXPCOM(nsIServiceManager*) [XPCOMInit.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 641 + 0xd]
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3260   rsp = 0x00007b6107ee31c0
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -      rip = 0x00007b60fcd6bb80
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -  11  libxul.so!XRE_XPCShellMain(int, char**, char**, XREShellData const*) [XPCShellImpl.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 1389 + 0x7]
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3690   rsp = 0x00007b6107ee3270
[task 2021-04-07T13:37:22.165Z] 13:37:22     INFO -      rip = 0x00007b60fd6fd24b
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -  12  libxul.so!GeckoStart [nsAndroidStartup.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 47 + 0x10]
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      rbp = 0x00007b6107ee36e0   rsp = 0x00007b6107ee36a0
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      rip = 0x00007b610078356d
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -  13  libmozglue.so!Java_org_mozilla_gecko_mozglue_GeckoLoader_nativeRun [APKOpen.cpp:269819a25f93255894887689cac0df8e7dbf3cb5 : 388 + 0x2d]
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      rbp = 0x00007b6107ee3770   rsp = 0x00007b6107ee36f0
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      rip = 0x00007b6107213103
[task 2021-04-07T13:37:22.166Z] 13:37:22     INFO -      Found by: previous frame's frame pointer
[task 2021-04-07T13:37:22.167Z] 13:37:22     INFO -  14  base.odex + 0xcb55ff```
Component: XPConnect → XPCOM

XPCShell test, crashing with MOZ_ASSERT(!mOwningThread) (destroying a still-owned lock!)

Some kind of TimerThread dtor running from a nsObserverList dtor.

See Also: → 1703694
Summary: Intermittent js/xpconnect/tests/unit/test_crypto.js | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()] → Intermittent Android xpcshell js/xpconnect/tests/unit/test_crypto.js | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()]
Summary: Intermittent Android xpcshell js/xpconnect/tests/unit/test_crypto.js | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()] → Intermittent Android xpcshell | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()]
Crash Signature: [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()] → [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex]
Summary: Intermittent Android xpcshell | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex()] → Intermittent Android xpcshell | application crashed [@ mozilla::OffTheBooksMutex::~OffTheBooksMutex]
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago → 1 year ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.