Closed Bug 1761118 Opened 3 years ago Closed 3 years ago

Intermittent [tier2] Assertion failure: aOther.mValue != 0 (Division by zero), at /builds/worker/workspace/obj-build/dist/include/mozilla/TimeStamp.h:206

Categories

(Core :: Panning and Zooming, defect, P5)

Product:
Core
Component:
Panning and Zooming
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: assertion, intermittent-failure)

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

[task 2022-03-23T19:38:30.950Z] 19:38:30     INFO - GECKO(8932) | helper_bug1756529.html | PromiseApzRepaintsFlushed: Flushed APZ repaints, waiting for callback...
[task 2022-03-23T19:38:30.987Z] 19:38:30     INFO - GECKO(8932) | helper_bug1756529.html | PromiseApzRepaintsFlushed: APZ flush done
[task 2022-03-23T19:38:31.002Z] 19:38:31     INFO - GECKO(8932) | Assertion failure: aOther.mValue != 0 (Division by zero), at /builds/worker/workspace/obj-build/dist/include/mozilla/TimeStamp.h:206
[task 2022-03-23T19:38:31.097Z] 19:38:31     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2022-03-23T19:38:40.863Z] 19:38:40     INFO - GECKO(8932) | #01: mozilla::ScrollAnimationBezierPhysics::PositionAt(mozilla::TimeStamp const&) [layout/generic/ScrollAnimationBezierPhysics.cpp:127]
[task 2022-03-23T19:38:40.864Z] 19:38:40     INFO - GECKO(8932) | #02: mozilla::layers::GenericScrollAnimation::DoSample(mozilla::layers::FrameMetrics&, mozilla::BaseTimeDuration<mozilla::TimeDurationValueCalculator> const&) [gfx/layers/apz/src/GenericScrollAnimation.cpp:75]
[task 2022-03-23T19:38:40.865Z] 19:38:40     INFO - GECKO(8932) | #03: mozilla::layers::AsyncPanZoomController::UpdateAnimation(mozilla::RecursiveMutexAutoLock const&, mozilla::layers::SampleTime const&, nsTArray<RefPtr<mozilla::Runnable> >*) [gfx/layers/apz/src/AsyncPanZoomController.cpp:4395]
[task 2022-03-23T19:38:40.865Z] 19:38:40     INFO - GECKO(8932) | #04: mozilla::layers::AsyncPanZoomController::AdvanceAnimations(mozilla::layers::SampleTime const&) [gfx/layers/apz/src/AsyncPanZoomController.cpp:4462]
[task 2022-03-23T19:38:40.866Z] 19:38:40     INFO - GECKO(8932) | #05: mozilla::layers::APZCTreeManager::AdvanceAnimationsInternal(mozilla::detail::BaseAutoLock<mozilla::Mutex &> const&, mozilla::layers::SampleTime const&) [gfx/layers/apz/src/APZCTreeManager.cpp:953]
[task 2022-03-23T19:38:40.867Z] 19:38:40     INFO - GECKO(8932) | #06: mozilla::layers::APZCTreeManager::SampleForWebRender(mozilla::Maybe<mozilla::layers::BaseTransactionId<mozilla::VsyncIdType> > const&, mozilla::wr::TransactionWrapper&, mozilla::layers::SampleTime const&) [gfx/layers/apz/src/APZCTreeManager.cpp:728]
[task 2022-03-23T19:38:40.868Z] 19:38:40     INFO - GECKO(8932) | #07: mozilla::layers::APZSampler::SampleForWebRender(mozilla::Maybe<mozilla::layers::BaseTransactionId<mozilla::VsyncIdType> > const&, mozilla::wr::TransactionWrapper&) [gfx/layers/apz/src/APZSampler.cpp:101]
[task 2022-03-23T19:38:40.868Z] 19:38:40     INFO - GECKO(8932) | #08: static mozilla::layers::APZSampler::SampleForWebRender(mozilla::wr::WrWindowId const&, unsigned long long const*, mozilla::wr::Transaction*) [gfx/layers/apz/src/APZSampler.cpp:70]
[task 2022-03-23T19:38:40.869Z] 19:38:40     INFO - GECKO(8932) | #09: apz_sample_transforms(mozilla::wr::WrWindowId, unsigned long long const*, mozilla::wr::Transaction*) [gfx/layers/apz/src/APZSampler.cpp:196]
[task 2022-03-23T19:38:40.870Z] 19:38:40     INFO - GECKO(8932) | #10: webrender_bindings::bindings::impl$20::sample(webrender_bindings::bindings::SamplerCallback*, webrender_api::DocumentId, enum$<core::option::Option<u64> >) [gfx/webrender_bindings/src/bindings.rs:1063]
[task 2022-03-23T19:38:40.871Z] 19:38:40     INFO - GECKO(8932) | #11: webrender::render_backend::RenderBackend::update_document(webrender_api::DocumentId, alloc::vec::Vec<enum$<webrender::render_api::ResourceUpdate>,alloc::alloc::Global>, alloc::vec::Vec<enum$<webrender::render_api::FrameMsg>,alloc::alloc::Global>, alloc::vec::Vec<webrender_api::NotificationRequest,alloc::alloc::Global>, bool, webrender_api::RenderReasons, enum$<core::option::Option<u64> >, bool, unsigned int*, bool) [gfx/wr/webrender/src/render_backend.rs:1311]
[task 2022-03-23T19:38:40.871Z] 19:38:40     INFO - GECKO(8932) | #12: webrender::render_backend::RenderBackend::process_api_msg(enum$<webrender::render_api::ApiMsg>, unsigned int*) [gfx/wr/webrender/src/render_backend.rs:1081]
[task 2022-03-23T19:38:40.872Z] 19:38:40     INFO - GECKO(8932) | #13: std::sys_common::backtrace::__rust_begin_short_backtrace<webrender::renderer::impl$13::new::closure$5,tuple$<> >(webrender::renderer::impl$13::new::closure$5) [git:github.com/rust-lang/rust:../9d1b2106e23b1abd32fce1f17267604a5102f57a/library/std/src/sys_common/backtrace.rs:9d1b2106e23b1abd32fce1f17267604a5102f57a:123]
[task 2022-03-23T19:38:40.873Z] 19:38:40     INFO - GECKO(8932) | #14: core::ops::function::FnOnce::call_once<std::thread::impl$0::spawn_unchecked::closure$1,tuple$<> >(std::thread::impl$0::spawn_unchecked::closure$1*) [git:github.com/rust-lang/rust:../9d1b2106e23b1abd32fce1f17267604a5102f57a/library/core/src/ops/function.rs:9d1b2106e23b1abd32fce1f17267604a5102f57a:227]
[task 2022-03-23T19:38:40.874Z] 19:38:40     INFO - GECKO(8932) | #15: std::sys::windows::thread::impl$0::new::thread_start() [git:github.com/rust-lang/rust:../9d1b2106e23b1abd32fce1f17267604a5102f57a//library/std/src/sys/windows/thread.rs:9d1b2106e23b1abd32fce1f17267604a5102f57a:58]
[task 2022-03-23T19:38:40.874Z] 19:38:40     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2022-03-23T19:38:40.874Z] 19:38:40     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2022-03-23T19:38:40.874Z] 19:38:40     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2022-03-23T19:38:40.875Z] 19:38:40     INFO - GECKO(8932) | #16: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x17034]
[task 2022-03-23T19:38:40.932Z] 19:38:40     INFO - GECKO(8932) | #17: patched_BaseThreadInitThunk(int, void*, void*) [toolkit/xre/dllservices/mozglue/WindowsDllBlocklist.cpp:568]
[task 2022-03-23T19:38:40.935Z] 19:38:40     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2022-03-23T19:38:40.935Z] 19:38:40     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2022-03-23T19:38:40.936Z] 19:38:40     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)

Backtrace suggests ScrollAnimationBezierPhysics::mDuration is zero.

That's computed by ComputeDuration(), clamped to ScrollAnimationBezierPhysicsSettings::{mMinMS,mMaxMS}. Those are deliberately left as zero if smooth scrolling is disabled (with some codepaths implementing "instant scrolling" by running a zero-duration smooth scroll animation).

So, this seems like a longstanding issue that we're just noticing now due to new test coverage.

(In reply to Botond Ballo [:botond] from comment #1)

(with some codepaths implementing "instant scrolling" by running a zero-duration smooth scroll animation).

Actually, that shouldn't happen in the compositor codepath, which is what the stack trace shows.

So maybe this is a case of the prefs changing in between the time we check the pref for whether to do a smooth scroll at all, and the time we check the duration prefs. It's not immediately obvious how that's possible, as the test uses await SpecialPowers.pushPrefEnv() to change the prefs, and that should propagate the pref change to all processes before continuing.

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

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.