Closed Bug 1769517 Opened 2 years ago Closed 2 years ago

High Frequency Assertion failure: (aA != 0 || (!IsInfinite(aB))) && ((aA != (-9223372036854775807i64 - 1) && aA != 9223372036854775807i64) || aB != 0.0) (Multiplication of infinity by zero), at /builds/worker/workspace/obj-build/dist/include/mozilla/Stick

Categories

(Core :: CSS Transitions and Animations, defect, P5)

defect

Tracking

()

RESOLVED FIXED
102 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox100 --- unaffected
firefox101 --- unaffected
firefox102 --- fixed

People

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

References

(Regression)

Details

(Keywords: assertion, intermittent-failure, regression)

Attachments

(1 file)

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=378179823&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HfDtoZOfSVOJJub4fEy-rw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/HfDtoZOfSVOJJub4fEy-rw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2022-05-16T09:36:42.583Z] 09:36:42     INFO - TEST-START | /html/rendering/widgets/appearance/appearance-animation-001.html
[task 2022-05-16T09:36:42.604Z] 09:36:42     INFO - PID 6928 | [Parent 1576, Main Thread] WARNING: NS_ENSURE_TRUE(uri) failed: file /builds/worker/checkouts/gecko/caps/BasePrincipal.cpp:1358
[task 2022-05-16T09:36:42.678Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.678Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.684Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.695Z] 09:36:42     INFO - PID 6928 | 1652693802695	Marionette	INFO	Testing http://web-platform.test:8000/html/rendering/widgets/appearance/appearance-animation-001.html == http://web-platform.test:8000/html/rendering/widgets/appearance/appearance-transition-001-ref.html
[task 2022-05-16T09:36:42.709Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.710Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.710Z] 09:36:42     INFO - PID 6928 | JavaScript error: chrome://remote/content/shared/TabManager.jsm, line 311: TypeError: can't access property "linkedBrowser", tab is undefined
[task 2022-05-16T09:36:42.712Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.727Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.729Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.729Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  webrender::device::gl] Cropping texture upload Box2D((0, 0), (0, 1)) to None
[task 2022-05-16T09:36:42.733Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.744Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.752Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.755Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.758Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3359
[task 2022-05-16T09:36:42.760Z] 09:36:42     INFO - PID 6928 | [2022-05-16T09:36:42Z WARN  audioipc2::ipccore] AudioIPC Server RPC: EventLoop drop - closing connection for Token(0) failed: Custom { kind: NotFound, error: "I/O source not registered with `Registry`" }
[task 2022-05-16T09:36:42.761Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-05-16T09:36:42.762Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.763Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.764Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_StartedGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.764Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: IPC message 'PIdleScheduler::Msg_DoneGC' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:509
[task 2022-05-16T09:36:42.768Z] 09:36:42     INFO - PID 6928 | [Child 1816, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4571
[task 2022-05-16T09:36:42.968Z] 09:36:42     INFO - PID 6928 | [Child 3144, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-05-16T09:36:43.004Z] 09:36:43     INFO - PID 6928 | Assertion failure: (aA != 0 || (!IsInfinite(aB))) && ((aA != (-9223372036854775807i64 - 1) && aA != 9223372036854775807i64) || aB != 0.0) (Multiplication of infinity by zero), at /builds/worker/workspace/obj-build/dist/include/mozilla/StickyTimeDuration.h:159
[task 2022-05-16T09:36:43.106Z] 09:36:43     INFO - STDOUT: Initializing stack-fixing for the first stack frame, this may take a while...
[task 2022-05-16T09:36:43.732Z] 09:36:43  WARNING - Failed to get assertion count; window was closed
[task 2022-05-16T09:36:43.742Z] 09:36:43  WARNING - Failed to get assertion count; window was closed
[task 2022-05-16T09:36:43.823Z] 09:36:43     INFO - mozcrash Copy/paste: Z:\task_165268870942325\fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --human C:\Users\task_165268870942325\AppData\Local\Temp\tmpte1pjqf7\minidumps\540fa1f5-4ce6-472c-840c-fcaf3d3a4020.dmp Z:\task_165268870942325\build\symbols
[task 2022-05-16T09:36:48.932Z] 09:36:48     INFO - mozcrash Saved minidump as Z:\task_165268870942325\build\blobber_upload_dir\540fa1f5-4ce6-472c-840c-fcaf3d3a4020.dmp
[task 2022-05-16T09:36:48.938Z] 09:36:48     INFO - mozcrash Saved app info as Z:\task_165268870942325\build\blobber_upload_dir\540fa1f5-4ce6-472c-840c-fcaf3d3a4020.extra
[task 2022-05-16T09:36:49.067Z] 09:36:49     INFO - PROCESS-CRASH | /html/rendering/widgets/appearance/appearance-animation-001.html | application crashed [@ mozilla::dom::CSSAnimation::QueueEvents(mozilla::BaseTimeDuration<mozilla::StickyTimeDurationValueCalculator> const&)]
[task 2022-05-16T09:36:49.068Z] 09:36:49     INFO - Mozilla crash reason: MOZ_ASSERT((aA != 0 || (!IsInfinite(aB))) && ((aA != (-9223372036854775807i64 - 1) && aA != 9223372036854775807i64) || aB != 0.0)) (Multiplication of infinity by zero)
[task 2022-05-16T09:36:49.068Z] 09:36:49     INFO - Crash dump filename: C:\Users\task_165268870942325\AppData\Local\Temp\tmpte1pjqf7\minidumps\540fa1f5-4ce6-472c-840c-fcaf3d3a4020.dmp
[task 2022-05-16T09:36:49.068Z] 09:36:49     INFO - Operating system: Windows NT
[task 2022-05-16T09:36:49.068Z] 09:36:49     INFO -                   10.0.19041
<...>
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x76820000 - 0x76894fff  sechost.dll  10.0.19041.906
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x768a0000 - 0x7695efff  msvcrt.dll  7.0.19041.546
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x76b80000 - 0x76c6ffff  kernel32.dll  10.0.19041.1348
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x76c70000 - 0x76ceafff  msvcp_win.dll  10.0.19041.789
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x772e0000 - 0x773d9fff  crypt32.dll  10.0.19041.1320
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x77440000 - 0x77446fff  nsi.dll  10.0.19041.610
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x774e0000 - 0x774f8fff  bcrypt.dll  10.0.19041.1023
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x77830000 - 0x77839fff  wow64cpu.dll  10.0.19041.662
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 0x77840000 - 0x779e2fff  ntdll.dll  10.0.19041.1288
[task 2022-05-16T09:36:49.174Z] 09:36:49     INFO - 
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - Unloaded modules:
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - 0x76270000 - 0x762f6fff  shcore.dll
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - 
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - Unimplemented streams encountered:
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - Stream 0x00000000 UnusedStream (Official) @ 0x00000000
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - Stream 0x00000016 ProcessVmCountersStream (Official) @ 0x0000212c
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - Stream 0x00000015 SystemMemoryInfoStream (Official) @ 0x00001f40
[task 2022-05-16T09:36:49.175Z] 09:36:49  WARNING - Found a crash dump; should change status from ERROR to CRASH but this causes instability
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - TEST-ERROR | /html/rendering/widgets/appearance/appearance-animation-001.html | took 6484ms
[task 2022-05-16T09:36:49.175Z] 09:36:49     INFO - TEST-START | /html/rendering/widgets/appearance/appearance-animation-002.html
Flags: needinfo?(james)

I guess if there's an assert here it's a bug we should investigate. I'm not quite sure whether the bug is more appearance/widget related or more animations related, but I'm going to guess it's more likely to originate in the input so hopefully Emilio is the right person to take a look.

Flags: needinfo?(james) → needinfo?(emilio)
Has Regression Range: --- → yes

This is more animation code than layout per se. Boris / Hiro, any idea? It seems the test-case has a massive (1e10) duration, with a steps() function...

Flags: needinfo?(emilio)

err, see above.

Component: DOM: Core & HTML → CSS Transitions and Animations
Flags: needinfo?(hikezoe.birchill)
Flags: needinfo?(boris.chiou)

The calculation in question is here in CSSAnimation::QueueEvents;

    uint64_t iterationBoundary = mPreviousIteration > currentIteration          
                                     ? currentIteration + 1                     
                                     : currentIteration;                        
    iterationStartTime = computedTiming.mDuration.MultDouble(                   
        (iterationBoundary - computedTiming.mIterationStart));

The computedTiming.mDuration is the large number (1e10), and (iterationBoundary - computedTiming.mIterationStart) is 0. I'd say in such cases, we can just skip calling StickyTimeDurationValueCalculator::Multiply, 1e10 isn't infinite.

Flags: needinfo?(hikezoe.birchill)
Assignee: nobody → hikezoe.birchill
Status: NEW → ASSIGNED
Flags: needinfo?(boris.chiou)
Pushed by hikezoe.birchill@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/723d1de5a1db
Skip multiplying to calculate iteration start time when the current iteration count is zero. r=boris
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 102 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: