Closed Bug 1917917 Opened 1 year ago Closed 1 year ago

Intermittent Assertion failure: run->mMagic == 0x384adf93, at /builds/worker/checkouts/gecko/memory/build/mozjemalloc.cpp:3688

Categories

(Core :: Memory Allocator, defect, P5)

Product:
Core
Component:
Memory Allocator
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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

[task 2024-09-10T18:17:57.642Z] 18:17:57     INFO - TEST-START | widget/tests/test_sizemode_events.xhtml
[task 2024-09-10T18:17:57.645Z] 18:17:57     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-09-10T18:17:57.645Z] 18:17:57     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-09-10T18:17:57.803Z] 18:17:57     INFO - GECKO(2836) | 1725992277802	RemoteAgent	TRACE	Received observer notification domwindowopened
[task 2024-09-10T18:17:57.803Z] 18:17:57     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1497
[task 2024-09-10T18:17:57.926Z] 18:17:57     INFO - GECKO(2836) | [WARN  webrender::device::gl] Missing optimized shader source for gpu_cache_update
[task 2024-09-10T18:17:57.959Z] 18:17:57     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:57.961Z] 18:17:57     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:57.961Z] 18:17:57     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.099Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.100Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.100Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.105Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.106Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.106Z] 18:17:58     INFO - GECKO(2836) | [WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2024-09-10T18:17:58.943Z] 18:17:58     INFO - GECKO(2836) | MEMORY STAT | vsize 20123MB | residentFast 668MB | heapAllocated 304MB
[task 2024-09-10T18:17:59.960Z] 18:17:59     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/chrome/nsChromeRegistry.cpp:182
[task 2024-09-10T18:17:59.960Z] 18:17:59     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: 'NS_FAILED(rv)', file /builds/worker/checkouts/gecko/chrome/nsChromeProtocolHandler.cpp:73
[task 2024-09-10T18:17:59.973Z] 18:17:59     INFO - GECKO(2836) | [Parent 2836, Main Thread] WARNING: Failed to retarget HTML data delivery to the parser thread.: file /builds/worker/checkouts/gecko/parser/html/nsHtml5StreamParser.cpp:1215
[task 2024-09-10T18:18:00.025Z] 18:18:00     INFO - TEST-OK | widget/tests/test_sizemode_events.xhtml | took 2381ms
[task 2024-09-10T18:18:00.071Z] 18:18:00     INFO - GECKO(2836) | [2836] Assertion failure: run->mMagic == 0x384adf93, at /builds/worker/checkouts/gecko/memory/build/mozjemalloc.cpp:3688
[task 2024-09-10T18:18:00.074Z] 18:18:00     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2024-09-10T18:18:00.076Z] 18:18:00     INFO - GECKO(2836) | #01: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0x49dfb]
[task 2024-09-10T18:18:00.079Z] 18:18:00     INFO - GECKO(2836) | #02: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0x497bf]
[task 2024-09-10T18:18:00.080Z] 18:18:00     INFO - GECKO(2836) | #03: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0x4ac98]
[task 2024-09-10T18:18:11.873Z] 18:18:11     INFO - GECKO(2836) | #04: js::gc::GCRuntime::freeFromBackgroundThread(js::AutoLockHelperThreadState&) [js/src/gc/Sweeping.cpp:488]
[task 2024-09-10T18:18:11.874Z] 18:18:11     INFO - GECKO(2836) | #05: js::GCParallelTask::runTask(JS::GCContext*, js::AutoLockHelperThreadState&) [js/src/gc/GCParallelTask.cpp:219]
[task 2024-09-10T18:18:11.875Z] 18:18:11     INFO - GECKO(2836) | #06: js::GCParallelTask::runHelperThreadTask(js::AutoLockHelperThreadState&) [js/src/gc/GCParallelTask.cpp:201]
[task 2024-09-10T18:18:11.876Z] 18:18:11     INFO - GECKO(2836) | #07: js::GlobalHelperThreadState::runTaskLocked(JS::HelperThreadTask*, js::AutoLockHelperThreadState&) [js/src/vm/HelperThreads.cpp:651]
[task 2024-09-10T18:18:11.876Z] 18:18:11     INFO - GECKO(2836) | #08: JS::RunHelperThreadTask(JS::HelperThreadTask*) [js/src/vm/HelperThreads.cpp:595]
[task 2024-09-10T18:18:11.876Z] 18:18:11     INFO - GECKO(2836) | #09: HelperThreadTaskHandler::Run() [js/xpconnect/src/XPCJSContext.cpp:1136]
[task 2024-09-10T18:18:11.876Z] 18:18:11     INFO - GECKO(2836) | #10: mozilla::TaskController::RunPoolThread(mozilla::PoolThread*) [xpcom/threads/TaskController.cpp:350]
[task 2024-09-10T18:18:11.883Z] 18:18:11     INFO - GECKO(2836) | #11: _pt_root [nsprpub/pr/src/pthreads/ptthread.c:204]
[task 2024-09-10T18:18:11.884Z] 18:18:11     INFO - GECKO(2836) | #12: ??? [/builds/worker/workspace/build/application/firefox/firefox + 0x64c7d]
[task 2024-09-10T18:18:11.884Z] 18:18:11     INFO - GECKO(2836) | #13: ??? [/lib/x86_64-linux-gnu/libpthread.so.0 + 0x76db]
[task 2024-09-10T18:18:11.885Z] 18:18:11     INFO - GECKO(2836) | #14: clone [/lib/x86_64-linux-gnu/libc.so.6 + 0x121a3f]
[task 2024-09-10T18:18:11.886Z] 18:18:11     INFO - GECKO(2836) | #15: ??? (???:???)
[task 2024-09-10T18:18:11.888Z] 18:18:11     INFO - GECKO(2836) | ExceptionHandler::GenerateDump attempting to generate:</tmp/tmpzkznkvle.mozrunner/minidumps/0cd1af91-9072-0916-2a8c-111c2f480b23.dmp
[task 2024-09-10T18:18:11.888Z] 18:18:11     INFO - GECKO(2836) | ExceptionHandler::GenerateDump cloned child 3051
[task 2024-09-10T18:18:11.888Z] 18:18:11     INFO - GECKO(2836) | ExceptionHandler::SendContinueSignalToChild sent continue signal to child
[task 2024-09-10T18:18:11.889Z] 18:18:11     INFO - GECKO(2836) | ExceptionHandler::WaitForContinueSignal waiting for continue signal...
[task 2024-09-10T18:18:11.889Z] 18:18:11     INFO - GECKO(2836) | ExceptionHandler::GenerateDump minidump generation <succeeded
[task 2024-09-10T18:18:11.890Z] 18:18:11     INFO - GECKO(2836) | <[Utility 2958, IPC I/O Child] WARNING: [58FEB8120038BEA2.2510953CEDA59398]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-09-10T18:18:11.890Z] 18:18:11     INFO - GECKO(2836) | [Socket 2922, IPC I/O Child] WARNING: [8731A8EE9C78BC71.D97A97812076EC57]: Dropping message '<null>'; no connection to unknown peer 1.1: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:364
[task 2024-09-10T18:18:11.890Z] 18:18:11     INFO - GECKO(2836) | [Socket 2922, Main Thread] WARNING: Shutting down Socket process early due to a crash!: file /builds/worker/checkouts/gecko/netwerk/ipc/SocketProcessChild.cpp:234
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - GECKO(2836) | [Utility 2958, Main Thread] WARNING: Shutting down Utility process early due to a crash!: file /builds/worker/checkouts/gecko/ipc/glue/UtilityProcessChild.cpp:358
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - TEST-INFO | Main app process: exit 11
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - Buffered messages finished
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - runtests.py | Application ran for: 0:01:38.873110
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - zombiecheck | Reading PID log: /tmp/tmppckqqx8dpidlog
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - ==> process 2836 launched child process 2922
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - ==> process 2836 launched child process 2958
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - zombiecheck | Checking for orphan process with PID: 2922
[task 2024-09-10T18:18:11.891Z] 18:18:11     INFO - zombiecheck | Checking for orphan process with PID: 2958
[task 2024-09-10T18:18:11.892Z] 18:18:11     INFO - mozcrash Copy/paste: /builds/worker/fetches/minidump-stackwalk/minidump-stackwalk --symbols-url=https://symbols.mozilla.org/ --cyborg=/tmp/tmpbjv9278w/0cd1af91-9072-0916-2a8c-111c2f480b23.trace /tmp/tmpzkznkvle.mozrunner/minidumps/0cd1af91-9072-0916-2a8c-111c2f480b23.dmp /builds/worker/workspace/build/symbols
[task 2024-09-10T18:18:16.980Z] 18:18:16     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/0cd1af91-9072-0916-2a8c-111c2f480b23.dmp
[task 2024-09-10T18:18:16.980Z] 18:18:16     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/0cd1af91-9072-0916-2a8c-111c2f480b23.extra
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - PROCESS-CRASH | MOZ_DIAGNOSTIC_ASSERT(run->mMagic == 0x384adf93) [@ AllocInfo::GetInChunk] | widget/tests/test_sizemode_events.xhtml (finished) 
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Process type: main
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Process pid: 2871
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Mozilla crash reason: MOZ_DIAGNOSTIC_ASSERT(run->mMagic == 0x384adf93)
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Crash dump filename: /tmp/tmpzkznkvle.mozrunner/minidumps/0cd1af91-9072-0916-2a8c-111c2f480b23.dmp
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Operating system: Linux
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO -                   4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - CPU: amd64
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO -      family 6 model 85 stepping 7
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO -      4 CPUs
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Linux Ubuntu 18.04 - bionic (Ubuntu 18.04.6 LTS)
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - 
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Crash reason:  SIGSEGV / SEGV_MAPERR
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Crash address: 0x0000000000000000
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Crashing instruction: `mov dword [0x0], 0xe68`
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO - Memory accessed by instruction:
[task 2024-09-10T18:18:17.166Z] 18:18:17     INFO -   0. Address: 0x0000000000000000
[task 2024-09-10T18:18:17.167Z] 18:18:17     INFO -      Size: 4
[task 2024-09-10T18:18:17.167Z] 18:18:17     INFO - Process uptime: not available
[task 2024-09-10T18:18:17.167Z] 18:18:17     INFO - 
[task 2024-09-10T18:18:17.167Z] 18:18:17     INFO - Thread 17 TaskCon~ller #0 (crashed)
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -  0  firefox-bin!AllocInfo::GetInChunk(void const*, arena_chunk_t*, unsigned long) [mozjemalloc.cpp:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 3688 + 0x0]
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -      rax = 0x000056147a133380    rdx = 0x0000000000000000
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -      rcx = 0x000056147a226150    rbx = 0x00007f4b91ffa340
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -      rsi = 0x00007f4be9dd48b0    rdi = 0x00007f4be9dd3680
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -      rbp = 0x00007f4bc58fe790    rsp = 0x00007f4bc58fe790
[task 2024-09-10T18:18:17.168Z] 18:18:17     INFO -       r8 = 0x00007f4be9dd48b0     r9 = 0x00007f4bc58ff700
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      r10 = 0x0000000000000000    r11 = 0x0000000000000000
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      r12 = 0x00007f4be9800000    r13 = 0x00000000000000fa
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      r14 = 0x00000000000fa340    r15 = 0x00007f4b91f00000
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      rip = 0x000056147a163e0c
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -     Found by: given as instruction pointer in context
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -  1  firefox-bin!arena_dalloc(void*, unsigned long, arena_t*) [mozjemalloc.cpp:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 3970 + 0xd]
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      rbx = 0x00007f4b91ffa340    rbp = 0x00007f4bc58fe7e0
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      rsp = 0x00007f4bc58fe7a0    r12 = 0x00007f4be9800000
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      r13 = 0x00000000000000fa    r14 = 0x00000000000fa340
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      r15 = 0x00007f4b91f00000    rip = 0x000056147a1637bf
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -     Found by: call frame info
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -  2  firefox-bin!MozJemallocPHC::free(void*) [PHC.cpp:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 1797 + 0x4]
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      rbx = 0x0000000000058240    rbp = 0x00007f4bc58fe810
[task 2024-09-10T18:18:17.169Z] 18:18:17     INFO -      rsp = 0x00007f4bc58fe7f0    r12 = 0x00007f4b9c21a000
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r13 = 0x0000000000034370    r14 = 0x00007f4b91ffa340
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r15 = 0x0000000000000000    rip = 0x000056147a164c98
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -     Found by: call frame info
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -  3  libxul.so!mozilla::StringBuffer::Release() [StringBuffer.h:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 186]
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -     Found by: inlining
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -  4  libxul.so!js::gc::GCRuntime::freeFromBackgroundThread(js::AutoLockHelperThreadState&) [Sweeping.cpp:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 488 + 0x6d]
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      rbx = 0x0000000000058240    rbp = 0x00007f4bc58fe9e0
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      rsp = 0x00007f4bc58fe820    r12 = 0x00007f4b9c21a000
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r13 = 0x0000000000034370    r14 = 0x00007f4b91ffa340
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r15 = 0x0000000000000000    rip = 0x00007f4bda90811b
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -     Found by: call frame info
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -  5  libxul.so!js::GCParallelTask::runTask(JS::GCContext*, js::AutoLockHelperThreadState&) [GCParallelTask.cpp:cf3bca5aa1c4a06e35f51c59bb477c1f340b7e72 : 218 + 0xc]
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      rbx = 0x00007f4bc58fea40    rbp = 0x00007f4bc58fea30
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      rsp = 0x00007f4bc58fe9f0    r12 = 0x00000095f09ecdaf
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r13 = 0x0000000000000000    r14 = 0x0000000000000000
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -      r15 = 0x00007f4bc842e868    rip = 0x00007f4bda891ac0
[task 2024-09-10T18:18:17.170Z] 18:18:17     INFO -     Found by: call frame info
<...>
[task 2024-09-10T18:18:17.182Z] 18:18:17     INFO - 14  libpthread.so.0 + 0x76da
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -      rbx = 0x0000000000000000    rbp = 0x0000000000000000
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -      rsp = 0x00007f4bc58feec0    r12 = 0x00007f4bc58fef80
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -      r13 = 0x0000000000000000    r14 = 0x00007f4bc814d770
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -      r15 = 0x00007ffc9881efd0    rip = 0x00007f4be9ddf6db
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -     Found by: call frame info
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO - 15  libc.so.6 + 0x121a3e
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -      rsp = 0x00007f4bc58fef80    rip = 0x00007f4be9b08a3f
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -     Found by: stack scanning
[task 2024-09-10T18:18:17.183Z] 18:18:17     INFO -

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=478324567&repo=autoland

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

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.