Closed Bug 1543316 Opened 1 year ago Closed 6 months ago

Perma Linux64 debug W-sw Assertion failure: (mProxy->mDelayedUpdate == this), at /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerRegistrationProxy.cpp:280

Categories

(Core :: DOM: Service Workers, defect, P2)

defect

Tracking

()

RESOLVED FIXED

People

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

References

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell disabled])

Crash Data

Attachments

(3 files, 1 obsolete file)

Duplicate of this bug: 1545013
Duplicate of this bug: 1544672

The priority flag is not set for this bug.
:overholt, could you have a look please?

Flags: needinfo?(overholt)

Recent spikes here seem to have started with bug 1546407: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&revision=cfafd091d30107f974a22c06ef790bee7950c1cd&selectedJob=242352312&searchStr=linux%2Cx64%2Cdebug%2Cweb%2Cplatform%2Ctests%2Cwith%2Cserviceworker%2Credesign%2Ctest-linux64%2Fdebug-web-platform-tests-sw-e10s-14%2Cw-sw%28wpt14%29

Julian, can you please take a look?

There are 41 total failures in the last 3 days on linux64 debug.

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=242873572&repo=mozilla-inbound&lineNumber=45736

[task 2019-04-26T14:25:41.779Z] 14:25:41 INFO - TEST-START | /service-workers/service-worker/navigate-window.https.html
[task 2019-04-26T14:25:41.783Z] 14:25:41 INFO - Closing window 214748364801
[task 2019-04-26T14:25:41.913Z] 14:25:41 INFO - PID 11416 | Assertion failure: (mProxy->mDelayedUpdate == this), at /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerRegistrationProxy.cpp:280
[task 2019-04-26T14:25:42.080Z] 14:25:42 INFO - IOError on command, setting status to CRASH
[task 2019-04-26T14:25:42.088Z] 14:25:42 INFO - mozcrash Copy/paste: /builds/worker/workspace/build/linux64-minidump_stackwalk /tmp/tmpoPzo_T.mozrunner/minidumps/3e992a87-4cd1-7e55-deff-6373ec94726a.dmp /builds/worker/workspace/build/symbols
[task 2019-04-26T14:25:49.644Z] 14:25:49 INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/3e992a87-4cd1-7e55-deff-6373ec94726a.dmp
[task 2019-04-26T14:25:49.645Z] 14:25:49 INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/3e992a87-4cd1-7e55-deff-6373ec94726a.extra
[task 2019-04-26T14:25:49.843Z] 14:25:49 INFO - PROCESS-CRASH | /service-workers/service-worker/navigate-window.https.html | application crashed [@ mozilla::dom::ServiceWorkerRegistrationProxy::DelayedUpdate::Notify(nsITimer*)]
[task 2019-04-26T14:25:49.843Z] 14:25:49 INFO - Crash dump filename: /tmp/tmpoPzo_T.mozrunner/minidumps/3e992a87-4cd1-7e55-deff-6373ec94726a.dmp
[task 2019-04-26T14:25:49.843Z] 14:25:49 INFO - Operating system: Linux
[task 2019-04-26T14:25:49.844Z] 14:25:49 INFO - 0.0.0 Linux 4.4.0-1014-aws #14taskcluster1-Ubuntu SMP Tue Apr 3 10:27:00 UTC 2018 x86_64
[task 2019-04-26T14:25:49.844Z] 14:25:49 INFO - CPU: amd64
[task 2019-04-26T14:25:49.844Z] 14:25:49 INFO - family 6 model 62 stepping 4
[task 2019-04-26T14:25:49.845Z] 14:25:49 INFO - 4 CPUs
[task 2019-04-26T14:25:49.845Z] 14:25:49 INFO -
[task 2019-04-26T14:25:49.845Z] 14:25:49 INFO - GPU: UNKNOWN
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO -
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO - Crash reason: SIGSEGV /SEGV_MAPERR
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO - Crash address: 0x0
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO - Process uptime: not available
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO -
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO - Thread 0 (crashed)
[task 2019-04-26T14:25:49.846Z] 14:25:49 INFO - 0 libxul.so!mozilla::dom::ServiceWorkerRegistrationProxy::DelayedUpdate::Notify(nsITimer*) [ServiceWorkerRegistrationProxy.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 280 + 0x29]
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - rax = 0x00007fad09453ad8 rdx = 0x0000000000000000
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - rcx = 0x000055fa13b1c988 rbx = 0x00007ffed3effab8
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - rsi = 0x00007fad14c81770 rdi = 0x00007fad14c80540
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - rbp = 0x00007ffed3effa60 rsp = 0x00007ffed3effa10
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - r8 = 0x00007fad14c81770 r9 = 0x00007fad15d4d740
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - r10 = 0x0000000000000000 r11 = 0x0000000000000000
[task 2019-04-26T14:25:49.847Z] 14:25:49 INFO - r12 = 0x00007facf6fa62e0 r13 = 0x00007ffed3effac0
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - r14 = 0x00007facf6fa62e8 r15 = 0x00007facf3788b68
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - rip = 0x00007fad055f6fe8
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - Found by: given as instruction pointer in context
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - 1 libxul.so!nsTimerImpl::Fire(int) [nsTimerImpl.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 564 + 0xf]
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - rbx = 0x00007ffed3effab8 rbp = 0x00007ffed3effb20
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - rsp = 0x00007ffed3effa70 r12 = 0x00007facf3788b30
[task 2019-04-26T14:25:49.848Z] 14:25:49 INFO - r13 = 0x00007ffed3effac0 r14 = 0x0000000000000001
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - r15 = 0x00007facf3788b68 rip = 0x00007fad02e997e1
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - 2 libxul.so!nsTimerEvent::Run() [TimerThread.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 260 + 0x14]
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - rbx = 0x00007facf81fd020 rbp = 0x00007ffed3effb50
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - rsp = 0x00007ffed3effb30 r12 = 0x00007fad14651940
[task 2019-04-26T14:25:49.849Z] 14:25:49 INFO - r13 = 0x00000000ffffffff r14 = 0x0000000000000001
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - r15 = 0x0000000000000001 rip = 0x00007fad02e993cc
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - 3 libxul.so!nsThread::ProcessNextEvent(bool, bool*) [nsThread.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 1180 + 0xe]
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - rbx = 0x00007facf81fd020 rbp = 0x00007ffed3f00060
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - rsp = 0x00007ffed3effb60 r12 = 0x00007fad14651940
[task 2019-04-26T14:25:49.850Z] 14:25:49 INFO - r13 = 0x00000000ffffffff r14 = 0x0000000000000001
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - r15 = 0x0000000000000001 rip = 0x00007fad02ea05a6
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - 4 libxul.so!NS_ProcessNextEvent(nsIThread*, bool) [nsThreadUtils.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 486 + 0x10]
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - rbx = 0x0000000000000000 rbp = 0x00007ffed3f00090
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - rsp = 0x00007ffed3f00070 r12 = 0x0000000000000001
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - r13 = 0x00007fad00606f60 r14 = 0x00007ffed3f00077
[task 2019-04-26T14:25:49.851Z] 14:25:49 INFO - r15 = 0x0000000000000001 rip = 0x00007fad02ea2ad1
[task 2019-04-26T14:25:49.852Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.852Z] 14:25:49 INFO - 5 libxul.so!mozilla::ipc::MessagePump::Run(base::MessagePump::Delegate*) [MessagePump.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 88 + 0x7]
[task 2019-04-26T14:25:49.852Z] 14:25:49 INFO - rbx = 0x00007fad00606f40 rbp = 0x00007ffed3f000f0
[task 2019-04-26T14:25:49.852Z] 14:25:49 INFO - rsp = 0x00007ffed3f000a0 r12 = 0x0000000000000001
[task 2019-04-26T14:25:49.852Z] 14:25:49 INFO - r13 = 0x00007fad00606f60 r14 = 0x00007fad1465e430
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - r15 = 0x0000000000000001 rip = 0x00007fad033c6f12
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - 6 libxul.so!MessageLoop::RunInternal() [message_loop.cc:a2f7510851a4b7603cd25691f82712b2f77ec863 : 315 + 0x17]
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - rbx = 0x00007fad1465e430 rbp = 0x00007ffed3f00130
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - rsp = 0x00007ffed3f00100 r12 = 0x00007ffed3f00220
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - r13 = 0x00007ffed3f001f8 r14 = 0x00007ffed3f00140
[task 2019-04-26T14:25:49.853Z] 14:25:49 INFO - r15 = 0x00007ffed3f00220 rip = 0x00007fad03381e19
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - 7 libxul.so!MessageLoop::Run() [message_loop.cc:a2f7510851a4b7603cd25691f82712b2f77ec863 : 290 + 0x8]
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - rbx = 0x00007fad1465e430 rbp = 0x00007ffed3f00170
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - rsp = 0x00007ffed3f00140 r12 = 0x00007ffed3f00220
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - r13 = 0x00007ffed3f001f8 r14 = 0x00007ffed3f00140
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - r15 = 0x00007ffed3f00220 rip = 0x00007fad03381d74
[task 2019-04-26T14:25:49.854Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - 8 libxul.so!nsBaseAppShell::Run() [nsBaseAppShell.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 137 + 0xd]
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - rbx = 0x00007fad00638f90 rbp = 0x00007ffed3f00190
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - rsp = 0x00007ffed3f00180 r12 = 0x00007ffed3f00220
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - r13 = 0x00007ffed3f001f8 r14 = 0x00007fad14651940
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - r15 = 0x00007ffed3f00220 rip = 0x00007fad056a5b81
[task 2019-04-26T14:25:49.855Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - 9 libxul.so!nsAppStartup::Run() [nsAppStartup.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 270 + 0x12]
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - rbx = 0x00007facfb584b50 rbp = 0x00007ffed3f001b0
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - rsp = 0x00007ffed3f001a0 r12 = 0x00007ffed3f00220
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - r13 = 0x00007ffed3f001f8 r14 = 0x00007ffed3f003d0
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - r15 = 0x00007ffed3f00220 rip = 0x00007fad0660a763
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.856Z] 14:25:49 INFO - 10 libxul.so!XREMain::XRE_mainRun() [nsAppRunner.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 4578 + 0xe]
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - rbx = 0x0000000000000077 rbp = 0x00007ffed3f00300
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - rsp = 0x00007ffed3f001c0 r12 = 0x00007ffed3f00220
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - r13 = 0x00007ffed3f001f8 r14 = 0x00007ffed3f003d0
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - r15 = 0x00007ffed3f00220 rip = 0x00007fad066f30f1
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.857Z] 14:25:49 INFO - 11 libxul.so!XREMain::XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 4716 + 0x5]
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - rbx = 0x00007ffed3f003c8 rbp = 0x00007ffed3f003b0
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - rsp = 0x00007ffed3f00310 r12 = 0x0000000000000001
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - r13 = 0x00007ffed3f00400 r14 = 0x0000000000000000
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - r15 = 0x00007ffed3f003f8 rip = 0x00007fad066f3b48
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - 12 libxul.so!XRE_main(int, char**, mozilla::BootstrapConfig const&) [nsAppRunner.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 4797 + 0x10]
[task 2019-04-26T14:25:49.858Z] 14:25:49 INFO - rbx = 0x0000000000000005 rbp = 0x00007ffed3f00510
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - rsp = 0x00007ffed3f003c0 r12 = 0x00007ffed3f003c8
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - r13 = 0x00007ffed3f01648 r14 = 0x00007ffed3f00530
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - r15 = 0x00007ffed3f01648 rip = 0x00007fad066f4302
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - 13 firefox-bin!main [nsBrowserApp.cpp:a2f7510851a4b7603cd25691f82712b2f77ec863 : 212 + 0x1e]
[task 2019-04-26T14:25:49.859Z] 14:25:49 INFO - rbx = 0x00007fad0ad2d650 rbp = 0x00007ffed3f01560
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - rsp = 0x00007ffed3f00520 r12 = 0x000055fa13b090c4
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - r13 = 0x00007ffed3f01648 r14 = 0x0000000000000005
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - r15 = 0x00007ffed3f00530 rip = 0x000055fa13acabd6
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - 14 libc-2.23.so + 0x20830
[task 2019-04-26T14:25:49.860Z] 14:25:49 INFO - rbx = 0x0000000000000000 rbp = 0x000055fa13b08720
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - rsp = 0x00007ffed3f01570 r12 = 0x000055fa13aca868
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - r13 = 0x00007ffed3f01640 r14 = 0x0000000000000000
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - r15 = 0x0000000000000000 rip = 0x00007fad148db830
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - Found by: call frame info
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - 15 firefox-bin!<name omitted> [UniquePtr.h:a2f7510851a4b7603cd25691f82712b2f77ec863 : 274 + 0x17]
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - rsp = 0x00007ffed3f01590 rip = 0x000055fa13acaa39
[task 2019-04-26T14:25:49.861Z] 14:25:49 INFO - Found by: stack scanning

Flags: needinfo?(jseward)

This is permafailing for weeks in the 'new serviceworker' variant oft web-platform-tests on Linux64 debug. Until recently, permafail bug 1535924 hit earlier during the execution of that test suite chunk (also a serviceworker failure). With that gone, this issue has moved to the top now.

Flags: needinfo?(jseward)
Summary: Intermittent PID 8940 | Assertion failure: (mProxy->mDelayedUpdate == this), at /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerRegistrationProxy.cpp:280 → Perma Linux64 debug W-sw Assertion failure: (mProxy->mDelayedUpdate == this), at /builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerRegistrationProxy.cpp:280

Thanks. We'll get to this after we land our parent intercept work (bug 1231208).

Flags: needinfo?(overholt)
Priority: -- → P2

:overholt, I don't see work in bug 1231208 for a long time, it is unclear that that work will be completed in the next few days or even the nextweek. As this is perma failing- we really should disable this test- it doesn't meet the criteria of something we should be running in production.

:dluca, could you adjust this for linux64 serviceworkers to either be expected:fail or disabled?

Flags: needinfo?(dluca)
Flags: needinfo?(dluca)
Attachment #9061830 - Attachment description: Bug 1543316 - Disabled ServiceWorkerRegistrationProxy.cpp on SW Linux64 r=jmaher → Bug 1543316 - Disabled service_worker_idl.https.html on sw-e10s mode on Linux64.
Attachment #9061830 - Attachment description: Bug 1543316 - Disabled service_worker_idl.https.html on sw-e10s mode on Linux64. → Bug 1543316 - Disabled waiting.https.html.ini on sw-e10s mode on Linux64.
Attachment #9061830 - Attachment is obsolete: true
Keywords: leave-open
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by apavel@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/20d75ab71902
Disabled waiting.https.html.ini on sw-e10s mode on Linux64. r=jmaher
Attachment #9061830 - Attachment is obsolete: false
Duplicate of this bug: 1548548
Crash Signature: [@ mozilla::dom::ServiceWorkerRegistrationProxy::DelayedUpdate::Notify(nsITimer*)]
Duplicate of this bug: 1549280
Pushed by malexandru@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/481580bf3fe8
Disable multiple-update.https.html on sw-e10s r=jmaher
Attachment #9064435 - Attachment is obsolete: true
Depends on: 1231213

Hi Andrea, I see no recent occurrences but a pending patch attached here. Can we close this anyway?

Flags: needinfo?(apavel)

Both disablings have been removed.

Status: NEW → RESOLVED
Closed: 6 months ago
Flags: needinfo?(apavel)
Resolution: --- → FIXED
Assignee: nobody → malexandru
You need to log in before you can comment on or make changes to this bug.